Test Date: 1973-11-29 16:33
Analysis date: 2016-09-21 19:51
Logfile
hrData_40.log
[11:35:13.939] INFO: *** Welcome to pxar ***
[11:35:13.939] INFO: *** Today: 2016/09/21
[11:35:13.965] INFO: *** Version: v1.9.0-818-g96727
[11:35:13.965] INFO: readRocDacs: data/mq939/dacParameters35_C0.dat .. data/mq939/dacParameters35_C15.dat
[11:35:13.966] INFO: readTbmDacs: data/mq939/tbmParameters_C0a.dat .. data/mq939/tbmParameters_C0b.dat
[11:35:13.966] INFO: readMaskFile: data/mq939/defaultMaskFile.dat
[11:35:13.966] INFO: readTrimFile: data/mq939/trimParameters35_C0.dat .. data/mq939/trimParameters35_C15.dat
[11:35:14.115] INFO: clk: 4
[11:35:14.115] INFO: ctr: 4
[11:35:14.115] INFO: sda: 19
[11:35:14.115] INFO: tin: 9
[11:35:14.115] INFO: level: 15
[11:35:14.115] INFO: triggerdelay: 0
[11:35:14.115] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:35:14.115] INFO: Log level: DEBUG
[11:35:14.126] QUIET: Connection to board DTB_WRPRHI opened.
[11:35:14.130] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[11:35:14.132] INFO: RPC call hashes of host and DTB match: 398089610
[11:35:15.735] INFO: DUT info:
[11:35:15.735] INFO: The DUT currently contains the following objects:
[11:35:15.735] INFO: 2 TBM Cores tbm08c (2 ON)
[11:35:15.735] INFO: TBM Core alpha (0): 7 registers set
[11:35:15.735] INFO: TBM Core beta (1): 7 registers set
[11:35:15.735] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:35:15.735] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:15.736] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:35:15.736] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:35:15.736] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:35:15.736] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:35:15.736] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:35:15.736] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:35:15.736] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:15.736] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:35:15.736] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:35:15.736] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:35:15.736] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:35:15.737] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:35:15.738] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:35:15.739] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:35:15.742] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33693696
[11:35:15.742] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2ab90e0
[11:35:15.742] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x285b370
[11:35:15.742] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fb9f9d94010
[11:35:15.742] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fb9fffff510
[11:35:15.742] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33701888 fPxarMemory = 0x7fb9f9d94010
[11:35:15.743] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 392.3mA
[11:35:15.744] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.1mA
[11:35:15.744] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:35:16.246] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2ea8030
[11:35:16.305] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:35:16.305] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:35:16.305] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:35:16.311] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:35:16.311] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:35:16.311] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:35:16.319] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:35:16.320] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:35:16.327] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:35:16.327] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:35:16.353] INFO: PixTestCmd::init()
[11:35:16.363] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:35:16.363] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:35:16.363] INFO: readGainPedestalParameters data/mq939/phCalibrationFitErr35_C0.dat .. data/mq939/phCalibrationFitErr35_C15.dat
[11:35:16.613] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:35:16.613] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:35:16.632] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:35:16.640] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:35:16.640] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:35:16.640] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:35:16.669] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:35:16.669] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:35:16.669] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:35:16.669] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:35:16.669] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:35:16.677] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:35:16.677] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:35:16.697] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:35:16.698] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:35:16.698] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:35:16.698] INFO: readReadbackCal: data/mq939/readbackCal_C0.dat .. data/mq939/readbackCal_C15.dat
[11:35:16.718] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:35:16.726] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:35:16.726] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:35:16.726] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:35:16.726] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:35:16.738] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:35:16.738] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:35:34.578] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[11:35:35.386] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[11:35:35.386] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[11:35:35.386] INFO: ----------------------------------------------------------------------
[11:35:35.386] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[11:35:35.386] INFO: ----------------------------------------------------------------------
[11:35:36.360] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[11:35:48.966] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[11:35:48.970] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:36:16.543] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1242051 events.
[11:36:22.381] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1242051, pixels seen in all events: 5347495
[11:36:22.556] INFO: Resuming triggers.
[11:36:35.167] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[11:36:35.268] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:37:02.584] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1242307 events.
[11:37:08.407] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1242307, pixels seen in all events: 5343285
[11:37:08.643] INFO: Resuming triggers.
[11:37:21.258] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[11:37:21.302] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:37:48.684] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1242812 events.
[11:37:54.495] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1242812, pixels seen in all events: 5337021
[11:37:54.734] INFO: Resuming triggers.
[11:38:07.350] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[11:38:07.385] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:38:34.777] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1242925 events.
[11:38:40.611] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1242925, pixels seen in all events: 5335963
[11:38:40.853] INFO: Resuming triggers.
[11:38:50.484] INFO: data taking finished, elapsed time: 60 seconds.
[11:38:50.681] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:39:11.601] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 948850 events.
[11:39:16.056] DEBUG: <PixTestXray.cc/processData:L823> # events read: 948850, pixels seen in all events: 4073447
[11:39:16.150] INFO: PixTest:: pg_setup set to default.
[11:39:16.240] INFO: PixTestXray::doPhRun() done
[11:41:16.106] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:41:16.107] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 396.3mA
[11:41:16.108] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 475.1mA
[11:41:16.108] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[11:41:16.108] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:41:16.108] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:41:16.108] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:41:16.108] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:41:16.108] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:41:16.108] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:41:16.108] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:41:16.108] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:41:16.108] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:41:16.108] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:41:16.109] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:41:16.109] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:41:16.109] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:41:16.109] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:41:16.109] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:41:16.109] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:41:16.109] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:41:16.109] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:41:16.388] QUIET: Connection to board 58 closed.
[11:41:16.467] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[11:41:41.680] INFO: *** Welcome to pxar ***
[11:41:41.680] INFO: *** Today: 2016/09/21
[11:41:41.704] INFO: *** Version: v1.9.0-818-g96727
[11:41:41.704] INFO: readRocDacs: data/mq939/dacParameters35_C0.dat .. data/mq939/dacParameters35_C15.dat
[11:41:41.705] INFO: readTbmDacs: data/mq939/tbmParameters_C0a.dat .. data/mq939/tbmParameters_C0b.dat
[11:41:41.705] INFO: readMaskFile: data/mq939/defaultMaskFile.dat
[11:41:41.705] INFO: readTrimFile: data/mq939/trimParameters35_C0.dat .. data/mq939/trimParameters35_C15.dat
[11:41:41.854] INFO: clk: 4
[11:41:41.854] INFO: ctr: 4
[11:41:41.854] INFO: sda: 19
[11:41:41.854] INFO: tin: 9
[11:41:41.854] INFO: level: 15
[11:41:41.854] INFO: triggerdelay: 0
[11:41:41.854] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:41:41.854] INFO: Log level: DEBUG
[11:41:41.865] QUIET: Connection to board DTB_WRPRHI opened.
[11:41:41.869] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[11:41:41.871] INFO: RPC call hashes of host and DTB match: 398089610
[11:41:43.475] INFO: DUT info:
[11:41:43.475] INFO: The DUT currently contains the following objects:
[11:41:43.475] INFO: 2 TBM Cores tbm08c (2 ON)
[11:41:43.475] INFO: TBM Core alpha (0): 7 registers set
[11:41:43.475] INFO: TBM Core beta (1): 7 registers set
[11:41:43.475] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:41:43.475] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.475] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:41:43.476] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:41:43.477] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:41:43.478] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:41:43.479] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:41:43.479] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:41:43.479] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:41:43.479] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:41:43.479] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:41:43.479] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:41:43.479] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:41:43.479] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:41:43.479] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:41:43.479] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:41:43.479] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:41:43.481] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31375360
[11:41:43.481] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xd580e0
[11:41:43.481] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xafa370
[11:41:43.481] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fda3d393010
[11:41:43.481] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fda435fe510
[11:41:43.481] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31383552 fPxarMemory = 0x7fda3d393010
[11:41:43.482] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 393.1mA
[11:41:43.484] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.1mA
[11:41:43.484] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[11:41:43.980] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1146f70
[11:41:44.047] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:41:44.047] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:41:44.047] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:41:44.053] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:41:44.053] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:41:44.053] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:41:44.064] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:41:44.064] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:41:44.079] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:41:44.079] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:41:44.090] INFO: PixTestCmd::init()
[11:41:44.108] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:41:44.108] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:41:44.108] INFO: readGainPedestalParameters data/mq939/phCalibrationFitErr35_C0.dat .. data/mq939/phCalibrationFitErr35_C15.dat
[11:41:44.355] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:41:44.355] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:41:44.369] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:41:44.376] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:41:44.376] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:41:44.376] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:41:44.403] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:41:44.403] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:41:44.403] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:41:44.403] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:41:44.403] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:41:44.409] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:41:44.409] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:41:44.432] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:41:44.432] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:41:44.432] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:41:44.432] INFO: readReadbackCal: data/mq939/readbackCal_C0.dat .. data/mq939/readbackCal_C15.dat
[11:41:44.441] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:41:44.449] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:41:44.450] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:41:44.450] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:41:44.450] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:41:44.471] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:41:44.471] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:41:52.994] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[11:41:53.746] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[11:41:53.746] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[11:41:53.746] INFO: ----------------------------------------------------------------------
[11:41:53.746] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[11:41:53.746] INFO: ----------------------------------------------------------------------
[11:41:54.722] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[11:42:02.448] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[11:42:02.452] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:42:27.513] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761257 events.
[11:42:39.526] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761257, pixels seen in all events: 11118439
[11:42:39.781] INFO: Resuming triggers.
[11:42:47.511] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[11:42:47.555] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:43:12.672] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761525 events.
[11:43:24.647] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761525, pixels seen in all events: 11113025
[11:43:24.816] INFO: Resuming triggers.
[11:43:32.549] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:43:32.592] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:43:57.640] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761836 events.
[11:44:09.642] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761836, pixels seen in all events: 11110392
[11:44:09.785] INFO: Resuming triggers.
[11:44:17.516] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[11:44:17.585] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:44:42.630] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761612 events.
[11:44:54.606] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761612, pixels seen in all events: 11112759
[11:44:54.784] INFO: Resuming triggers.
[11:45:02.518] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[11:45:02.564] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:45:27.579] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761846 events.
[11:45:39.602] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761846, pixels seen in all events: 11110117
[11:45:39.782] INFO: Resuming triggers.
[11:45:47.516] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[11:45:47.559] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:46:12.685] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 761921 events.
[11:46:24.721] DEBUG: <PixTestXray.cc/processData:L823> # events read: 761921, pixels seen in all events: 11108800
[11:46:24.833] INFO: Resuming triggers.
[11:46:32.568] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[11:46:32.655] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:46:57.764] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 762055 events.
[11:47:09.786] DEBUG: <PixTestXray.cc/processData:L823> # events read: 762055, pixels seen in all events: 11106616
[11:47:09.966] INFO: Resuming triggers.
[11:47:15.921] INFO: data taking finished, elapsed time: 60 seconds.
[11:47:16.118] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:47:35.551] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 586627 events.
[11:47:44.828] DEBUG: <PixTestXray.cc/processData:L823> # events read: 586627, pixels seen in all events: 8546417
[11:47:44.971] INFO: PixTest:: pg_setup set to default.
[11:47:45.020] INFO: PixTestXray::doPhRun() done
[11:48:58.842] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:48:58.843] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 396.3mA
[11:48:58.844] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 475.1mA
[11:48:58.844] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[11:48:58.844] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:48:58.844] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:48:58.844] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:48:58.844] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:48:58.844] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:48:58.844] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:48:58.844] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:48:58.844] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:48:58.845] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:48:58.845] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:48:58.845] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:48:58.845] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:48:58.845] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:48:58.845] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:48:58.845] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:48:58.845] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:48:58.845] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:48:58.845] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:48:59.158] QUIET: Connection to board 58 closed.
[11:48:59.238] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[11:28:31.652] INFO: *** Welcome to pxar ***
[11:28:31.652] INFO: *** Today: 2016/09/21
[11:28:31.676] INFO: *** Version: v1.9.0-818-g96727
[11:28:31.676] INFO: readRocDacs: data/mq939/dacParameters35_C0.dat .. data/mq939/dacParameters35_C15.dat
[11:28:31.677] INFO: readTbmDacs: data/mq939/tbmParameters_C0a.dat .. data/mq939/tbmParameters_C0b.dat
[11:28:31.677] INFO: readMaskFile: data/mq939/defaultMaskFile.dat
[11:28:31.677] INFO: readTrimFile: data/mq939/trimParameters35_C0.dat .. data/mq939/trimParameters35_C15.dat
[11:28:31.829] INFO: clk: 4
[11:28:31.829] INFO: ctr: 4
[11:28:31.829] INFO: sda: 19
[11:28:31.829] INFO: tin: 9
[11:28:31.829] INFO: level: 15
[11:28:31.829] INFO: triggerdelay: 0
[11:28:31.829] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:28:31.829] INFO: Log level: DEBUG
[11:28:31.840] QUIET: Connection to board DTB_WRPRHI opened.
[11:28:31.843] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[11:28:31.846] INFO: RPC call hashes of host and DTB match: 398089610
[11:28:33.450] INFO: DUT info:
[11:28:33.450] INFO: The DUT currently contains the following objects:
[11:28:33.451] INFO: 2 TBM Cores tbm08c (2 ON)
[11:28:33.451] INFO: TBM Core alpha (0): 7 registers set
[11:28:33.451] INFO: TBM Core beta (1): 7 registers set
[11:28:33.451] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:28:33.451] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:28:33.451] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:28:33.451] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:28:33.452] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:28:33.453] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:28:33.454] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:28:33.455] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:28:33.455] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:28:33.457] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32727040
[11:28:33.457] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x24b10e0
[11:28:33.457] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2253370
[11:28:33.457] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f59b9d94010
[11:28:33.457] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f59bffff510
[11:28:33.457] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32735232 fPxarMemory = 0x7f59b9d94010
[11:28:33.458] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 392.3mA
[11:28:33.459] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.1mA
[11:28:33.459] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:28:33.957] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x28a0090
[11:28:34.021] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:28:34.021] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:28:34.021] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:28:34.027] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:28:34.027] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:28:34.027] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:28:34.037] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:28:34.037] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:28:34.063] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:28:34.063] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:28:34.083] INFO: PixTestCmd::init()
[11:28:34.099] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:28:34.100] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:28:34.100] INFO: readGainPedestalParameters data/mq939/phCalibrationFitErr35_C0.dat .. data/mq939/phCalibrationFitErr35_C15.dat
[11:28:34.346] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:28:34.346] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:28:34.360] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:28:34.366] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:28:34.366] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:28:34.366] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:28:34.392] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:28:34.392] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:28:34.392] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:28:34.392] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:28:34.392] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:28:34.398] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:28:34.398] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:28:34.420] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:28:34.420] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:28:34.420] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:28:34.421] INFO: readReadbackCal: data/mq939/readbackCal_C0.dat .. data/mq939/readbackCal_C15.dat
[11:28:34.430] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:28:34.439] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:28:34.439] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:28:34.439] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:28:34.439] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:28:34.458] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:28:34.458] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:28:41.573] INFO: ######################################################################
[11:28:41.573] INFO: PixTestAlive::doTest()
[11:28:41.573] INFO: ######################################################################
[11:28:41.577] INFO: ----------------------------------------------------------------------
[11:28:41.577] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:28:41.577] INFO: ----------------------------------------------------------------------
[11:28:41.579] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:41.957] INFO: Expecting 41600 events.
[11:28:46.350] INFO: 41600 events read in total (3675ms).
[11:28:46.497] INFO: Test took 4918ms.
[11:28:46.507] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:46.507] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[11:28:46.507] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:28:46.789] INFO: PixTestAlive::aliveTest() done
[11:28:46.790] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 1
[11:28:46.790] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 20 19 23 46 50 35 36 46 23 41 44 40 42 22 16 18
[11:28:46.815] INFO: ----------------------------------------------------------------------
[11:28:46.815] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:28:46.815] INFO: ----------------------------------------------------------------------
[11:28:46.818] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:47.195] INFO: Expecting 41600 events.
[11:28:50.316] INFO: 41600 events read in total (2406ms).
[11:28:50.316] INFO: Test took 3498ms.
[11:28:50.317] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:50.317] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:28:50.317] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:28:50.317] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:28:50.739] INFO: PixTestAlive::maskTest() done
[11:28:50.739] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:28:50.761] INFO: ----------------------------------------------------------------------
[11:28:50.761] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:28:50.761] INFO: ----------------------------------------------------------------------
[11:28:50.764] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:51.142] INFO: Expecting 41600 events.
[11:28:55.529] INFO: 41600 events read in total (3672ms).
[11:28:55.530] INFO: Test took 4766ms.
[11:28:55.540] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:55.540] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[11:28:55.540] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:28:55.911] INFO: PixTestAlive::addressDecodingTest() done
[11:28:55.911] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:28:55.912] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[11:29:02.258] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:29:02.258] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[11:29:05.154] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:29:05.154] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:29:05.154] INFO: ----------------------------------------------------------------------
[11:29:05.154] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:29:05.154] INFO: ----------------------------------------------------------------------
[11:29:05.293] INFO: Expecting 768 events.
[11:29:06.426] INFO: 768 events read in total (418ms).
[11:29:06.427] INFO: Test took 1267ms.
[11:29:06.433] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:07.230] INFO: Expecting 41600 events.
[11:29:10.573] INFO: 41600 events read in total (2816ms).
[11:29:10.578] INFO: Test took 4145ms.
[11:29:10.659] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:10.659] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 460308
[11:29:10.659] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:29:10.659] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:10.696] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:11.361] INFO: Expecting 41600 events.
[11:29:14.829] INFO: 41600 events read in total (2942ms).
[11:29:14.833] INFO: Test took 4137ms.
[11:29:14.910] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:14.910] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468267
[11:29:14.910] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:29:14.910] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:14.950] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:15.615] INFO: Expecting 41600 events.
[11:29:19.098] INFO: 41600 events read in total (2956ms).
[11:29:19.101] INFO: Test took 4151ms.
[11:29:19.179] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:19.179] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469230
[11:29:19.179] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:29:19.180] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:19.219] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:19.884] INFO: Expecting 41600 events.
[11:29:23.390] INFO: 41600 events read in total (2980ms).
[11:29:23.393] INFO: Test took 4174ms.
[11:29:23.470] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:23.470] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468845
[11:29:23.470] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:29:23.471] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:23.510] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:24.176] INFO: Expecting 41600 events.
[11:29:27.670] INFO: 41600 events read in total (2968ms).
[11:29:27.673] INFO: Test took 4163ms.
[11:29:27.750] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:27.751] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 470004
[11:29:27.751] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:29:27.751] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:27.790] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:28.455] INFO: Expecting 41600 events.
[11:29:31.964] INFO: 41600 events read in total (2982ms).
[11:29:31.968] INFO: Test took 4178ms.
[11:29:32.048] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:32.048] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 470000
[11:29:32.048] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:29:32.049] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:32.088] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:32.753] INFO: Expecting 41600 events.
[11:29:36.247] INFO: 41600 events read in total (2967ms).
[11:29:36.250] INFO: Test took 4162ms.
[11:29:36.327] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:36.327] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469094
[11:29:36.327] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:29:36.328] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:36.367] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:37.032] INFO: Expecting 41600 events.
[11:29:40.543] INFO: 41600 events read in total (2984ms).
[11:29:40.547] INFO: Test took 4180ms.
[11:29:40.624] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:40.624] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 470137
[11:29:40.624] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:29:40.625] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:40.664] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:41.329] INFO: Expecting 41600 events.
[11:29:44.840] INFO: 41600 events read in total (2984ms).
[11:29:44.843] INFO: Test took 4179ms.
[11:29:44.921] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:44.921] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469045
[11:29:44.921] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:29:44.921] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:44.961] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:45.626] INFO: Expecting 41600 events.
[11:29:49.130] INFO: 41600 events read in total (2978ms).
[11:29:49.133] INFO: Test took 4172ms.
[11:29:49.210] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:49.210] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469883
[11:29:49.210] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:29:49.210] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:49.250] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:49.915] INFO: Expecting 41600 events.
[11:29:53.353] INFO: 41600 events read in total (2911ms).
[11:29:53.356] INFO: Test took 4106ms.
[11:29:53.434] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:53.434] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 470208
[11:29:53.434] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:29:53.435] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:53.474] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:54.140] INFO: Expecting 41600 events.
[11:29:57.644] INFO: 41600 events read in total (2978ms).
[11:29:57.647] INFO: Test took 4173ms.
[11:29:57.724] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:57.724] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469513
[11:29:57.724] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:29:57.725] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:57.764] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:58.429] INFO: Expecting 41600 events.
[11:30:01.950] INFO: 41600 events read in total (2994ms).
[11:30:01.954] INFO: Test took 4190ms.
[11:30:02.032] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:02.032] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469655
[11:30:02.032] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:30:02.032] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:02.071] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:02.736] INFO: Expecting 41600 events.
[11:30:06.258] INFO: 41600 events read in total (2995ms).
[11:30:06.262] INFO: Test took 4191ms.
[11:30:06.339] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:06.339] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 470890
[11:30:06.339] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:30:06.340] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:06.380] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:07.045] INFO: Expecting 41600 events.
[11:30:10.571] INFO: 41600 events read in total (3000ms).
[11:30:10.574] INFO: Test took 4194ms.
[11:30:10.651] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:10.651] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468991
[11:30:10.651] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:30:10.651] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:10.691] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:11.356] INFO: Expecting 41600 events.
[11:30:14.849] INFO: 41600 events read in total (2966ms).
[11:30:14.853] INFO: Test took 4162ms.
[11:30:14.931] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:14.931] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469633
[11:30:14.931] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:30:14.932] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:14.971] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:15.636] INFO: Expecting 41600 events.
[11:30:19.154] INFO: 41600 events read in total (2992ms).
[11:30:19.157] INFO: Test took 4186ms.
[11:30:19.234] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:19.234] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469142
[11:30:19.234] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:30:19.234] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:19.274] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:19.939] INFO: Expecting 41600 events.
[11:30:23.461] INFO: 41600 events read in total (2996ms).
[11:30:23.464] INFO: Test took 4190ms.
[11:30:23.542] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:23.543] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 469768
[11:30:23.543] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:30:23.543] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:23.583] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:24.248] INFO: Expecting 41600 events.
[11:30:27.758] INFO: 41600 events read in total (2983ms).
[11:30:27.762] INFO: Test took 4179ms.
[11:30:27.839] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:27.839] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 468985
[11:30:27.839] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:30:27.839] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:27.879] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:28.544] INFO: Expecting 41600 events.
[11:30:31.880] INFO: 41600 events read in total (2810ms).
[11:30:31.883] INFO: Test took 4004ms.
[11:30:31.961] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:31.961] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 462786
[11:30:31.961] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:30:31.961] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:32.221] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 168 eff = 0.999567
[11:30:32.221] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 155 eff = 0.999736
[11:30:32.222] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 167 eff = 0.999567
[11:30:32.222] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 168 eff = 0.999255
[11:30:32.222] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 159 eff = 0.998894
[11:30:32.222] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 154 eff = 0.99899
[11:30:32.222] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 167 eff = 0.998918
[11:30:32.222] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 161 eff = 0.999111
[11:30:32.222] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 183 eff = 0.999183
[11:30:32.223] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 163 eff = 0.998894
[11:30:32.223] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 185 eff = 0.99899
[11:30:32.223] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 195 eff = 0.999135
[11:30:32.223] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 217 eff = 0.999014
[11:30:32.223] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 196 eff = 0.999447
[11:30:32.223] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 164 eff = 0.999808
[11:30:32.223] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 200 eff = 0.999519
[11:30:49.650] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:30:49.650] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:30:49.650] INFO: ----------------------------------------------------------------------
[11:30:49.650] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:30:49.650] INFO: ----------------------------------------------------------------------
[11:30:49.651] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:30:49.651] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:30:49.651] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:30:49.651] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:30:49.651] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:30:49.651] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:30:49.651] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:30:49.651] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:30:49.651] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:30:49.651] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:30:49.651] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:30:49.651] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:30:49.669] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:50.339] INFO: Expecting 208000 events.
[11:31:03.809] INFO: 208000 events read in total (12943ms).
[11:31:03.820] INFO: Test took 14151ms.
[11:31:04.185] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:04.185] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2065998
[11:31:04.185] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:31:04.185] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:04.557] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 1
[11:31:04.557] INFO: number of red-efficiency pixels: 117 62 130 186 229 284 306 287 197 255 235 261 218 122 73 74
[11:31:04.557] INFO: number of X-ray hits detected: 87300 58599 96802 145423 155930 155413 156377 147427 148911 158062 157762 148865 153906 100416 58042 70205
[11:31:04.557] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:31:04.557] INFO: number of Vcal hits detected: 207881 207937 207868 207810 207706 207707 207684 207703 207794 207735 207750 207724 207766 207875 207925 207877
[11:31:04.557] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[11:31:04.557] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[11:31:04.557] INFO: X-ray hit rate [MHz/cm2]: 25.6 17.2 28.4 42.6 45.7 45.6 45.8 43.2 43.6 46.3 46.2 43.6 45.1 29.4 17.0 20.6
[11:31:04.557] INFO: PixTestHighRate::doXPixelAlive() done
[11:31:04.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:31:04.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:31:04.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:31:04.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:31:04.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:31:04.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:31:04.605] INFO: PixTest:: pg_setup set to default.
[11:31:19.610] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:31:19.610] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[11:31:22.210] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:31:22.211] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 396.3mA
[11:31:22.212] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 475.9mA
[11:31:22.212] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[11:31:22.212] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:31:22.212] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:31:22.212] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:31:22.212] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:31:22.212] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:31:22.212] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:31:22.212] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:31:22.212] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:31:22.212] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:31:22.212] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:31:22.307] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:31:22.307] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:31:22.321] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:31:22.321] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:31:22.321] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:31:22.321] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:31:22.321] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:31:22.321] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:31:22.323] QUIET: Connection to board 58 closed.
[11:31:22.403] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[11:24:49.001] INFO: *** Welcome to pxar ***
[11:24:49.001] INFO: *** Today: 2016/09/21
[11:24:49.024] INFO: *** Version: v1.9.0-818-g96727
[11:24:49.024] INFO: readRocDacs: data/mq939/dacParameters35_C0.dat .. data/mq939/dacParameters35_C15.dat
[11:24:49.025] INFO: readTbmDacs: data/mq939/tbmParameters_C0a.dat .. data/mq939/tbmParameters_C0b.dat
[11:24:49.025] INFO: readMaskFile: data/mq939/defaultMaskFile.dat
[11:24:49.025] INFO: readTrimFile: data/mq939/trimParameters35_C0.dat .. data/mq939/trimParameters35_C15.dat
[11:24:49.175] INFO: clk: 4
[11:24:49.175] INFO: ctr: 4
[11:24:49.175] INFO: sda: 19
[11:24:49.175] INFO: tin: 9
[11:24:49.175] INFO: level: 15
[11:24:49.175] INFO: triggerdelay: 0
[11:24:49.175] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:24:49.175] INFO: Log level: DEBUG
[11:24:49.186] QUIET: Connection to board DTB_WRPRHI opened.
[11:24:49.189] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[11:24:49.192] INFO: RPC call hashes of host and DTB match: 398089610
[11:24:50.795] INFO: DUT info:
[11:24:50.795] INFO: The DUT currently contains the following objects:
[11:24:50.795] INFO: 2 TBM Cores tbm08c (2 ON)
[11:24:50.795] INFO: TBM Core alpha (0): 7 registers set
[11:24:50.795] INFO: TBM Core beta (1): 7 registers set
[11:24:50.795] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:24:50.795] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.795] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:24:50.796] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:24:50.797] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:24:50.798] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:24:50.799] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:24:50.799] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:24:50.799] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:24:50.799] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:24:50.799] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:24:50.801] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31371264
[11:24:50.801] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x204a0e0
[11:24:50.801] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1dec370
[11:24:50.801] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fc331d94010
[11:24:50.801] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fc337fff510
[11:24:50.801] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31379456 fPxarMemory = 0x7fc331d94010
[11:24:50.802] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 392.3mA
[11:24:50.803] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.1mA
[11:24:50.803] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[11:24:51.299] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2438f70
[11:24:51.361] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:24:51.361] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:24:51.361] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:24:51.367] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:24:51.367] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:24:51.367] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:24:51.377] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:24:51.377] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:24:51.389] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:24:51.389] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:24:51.403] INFO: PixTestCmd::init()
[11:24:51.413] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:24:51.413] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:24:51.413] INFO: readGainPedestalParameters data/mq939/phCalibrationFitErr35_C0.dat .. data/mq939/phCalibrationFitErr35_C15.dat
[11:24:51.663] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:24:51.663] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:24:51.677] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:24:51.683] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:24:51.683] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:24:51.684] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:24:51.707] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:24:51.707] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:24:51.707] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:24:51.707] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:24:51.707] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:24:51.714] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:24:51.714] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:24:51.728] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:24:51.728] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:24:51.728] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:24:51.728] INFO: readReadbackCal: data/mq939/readbackCal_C0.dat .. data/mq939/readbackCal_C15.dat
[11:24:51.744] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:24:51.754] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:24:51.754] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:24:51.754] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:24:51.754] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:24:51.776] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:24:51.776] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:25:00.221] INFO: ######################################################################
[11:25:00.221] INFO: PixTestAlive::doTest()
[11:25:00.221] INFO: ######################################################################
[11:25:00.225] INFO: ----------------------------------------------------------------------
[11:25:00.225] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:25:00.225] INFO: ----------------------------------------------------------------------
[11:25:00.227] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:00.607] INFO: Expecting 41600 events.
[11:25:04.965] INFO: 41600 events read in total (3640ms).
[11:25:05.113] INFO: Test took 4886ms.
[11:25:05.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:05.125] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[11:25:05.125] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:25:05.405] INFO: PixTestAlive::aliveTest() done
[11:25:05.405] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 1
[11:25:05.405] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 33 26 45 47 60 58 63 64 49 69 64 61 80 36 16 34
[11:25:05.431] INFO: ----------------------------------------------------------------------
[11:25:05.431] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:25:05.431] INFO: ----------------------------------------------------------------------
[11:25:05.435] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:05.816] INFO: Expecting 41600 events.
[11:25:08.945] INFO: 41600 events read in total (2414ms).
[11:25:08.946] INFO: Test took 3511ms.
[11:25:08.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:08.946] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:25:08.946] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:25:08.946] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:25:09.368] INFO: PixTestAlive::maskTest() done
[11:25:09.368] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:25:09.389] INFO: ----------------------------------------------------------------------
[11:25:09.389] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:25:09.389] INFO: ----------------------------------------------------------------------
[11:25:09.392] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:09.771] INFO: Expecting 41600 events.
[11:25:14.145] INFO: 41600 events read in total (3658ms).
[11:25:14.146] INFO: Test took 4754ms.
[11:25:14.157] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:14.157] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[11:25:14.157] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:25:14.528] INFO: PixTestAlive::addressDecodingTest() done
[11:25:14.528] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:25:14.528] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[11:25:27.922] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:25:27.922] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[11:25:31.194] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:25:31.194] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:25:31.194] INFO: ----------------------------------------------------------------------
[11:25:31.194] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:25:31.194] INFO: ----------------------------------------------------------------------
[11:25:31.333] INFO: Expecting 768 events.
[11:25:32.467] INFO: 768 events read in total (419ms).
[11:25:32.467] INFO: Test took 1267ms.
[11:25:32.473] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:33.271] INFO: Expecting 41600 events.
[11:25:36.844] INFO: 41600 events read in total (3047ms).
[11:25:36.849] INFO: Test took 4376ms.
[11:25:37.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:37.001] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 670536
[11:25:37.001] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:25:37.001] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:37.052] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:37.717] INFO: Expecting 41600 events.
[11:25:41.392] INFO: 41600 events read in total (3149ms).
[11:25:41.397] INFO: Test took 4345ms.
[11:25:41.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:41.511] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 676907
[11:25:41.511] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:25:41.511] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:41.565] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:42.230] INFO: Expecting 41600 events.
[11:25:45.957] INFO: 41600 events read in total (3201ms).
[11:25:45.962] INFO: Test took 4397ms.
[11:25:46.078] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:46.078] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 682808
[11:25:46.078] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:25:46.079] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:46.134] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:46.801] INFO: Expecting 41600 events.
[11:25:50.528] INFO: 41600 events read in total (3201ms).
[11:25:50.532] INFO: Test took 4398ms.
[11:25:50.647] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:50.647] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 682791
[11:25:50.647] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:25:50.647] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:50.703] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:51.370] INFO: Expecting 41600 events.
[11:25:55.105] INFO: 41600 events read in total (3209ms).
[11:25:55.109] INFO: Test took 4406ms.
[11:25:55.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:55.225] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 682332
[11:25:55.225] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:25:55.226] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:55.279] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:55.944] INFO: Expecting 41600 events.
[11:25:59.686] INFO: 41600 events read in total (3215ms).
[11:25:59.691] INFO: Test took 4412ms.
[11:25:59.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:59.806] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 682473
[11:25:59.806] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:25:59.806] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:59.860] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:00.525] INFO: Expecting 41600 events.
[11:26:04.276] INFO: 41600 events read in total (3224ms).
[11:26:04.281] INFO: Test took 4421ms.
[11:26:04.396] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:04.396] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684111
[11:26:04.397] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:26:04.397] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:04.451] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:05.115] INFO: Expecting 41600 events.
[11:26:08.874] INFO: 41600 events read in total (3232ms).
[11:26:08.878] INFO: Test took 4427ms.
[11:26:08.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:08.994] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 682548
[11:26:08.994] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:26:08.995] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:09.050] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:09.717] INFO: Expecting 41600 events.
[11:26:13.471] INFO: 41600 events read in total (3228ms).
[11:26:13.475] INFO: Test took 4425ms.
[11:26:13.590] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:13.590] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 682307
[11:26:13.590] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:26:13.590] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:13.644] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:14.309] INFO: Expecting 41600 events.
[11:26:18.059] INFO: 41600 events read in total (3223ms).
[11:26:18.063] INFO: Test took 4420ms.
[11:26:18.180] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:18.180] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 681825
[11:26:18.180] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:26:18.181] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:18.238] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:18.905] INFO: Expecting 41600 events.
[11:26:22.665] INFO: 41600 events read in total (3233ms).
[11:26:22.669] INFO: Test took 4431ms.
[11:26:22.785] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:22.785] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683250
[11:26:22.785] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:26:22.785] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:22.839] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:23.504] INFO: Expecting 41600 events.
[11:26:27.268] INFO: 41600 events read in total (3237ms).
[11:26:27.272] INFO: Test took 4433ms.
[11:26:27.388] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:27.388] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683071
[11:26:27.388] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:26:27.389] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:27.443] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:28.110] INFO: Expecting 41600 events.
[11:26:31.867] INFO: 41600 events read in total (3230ms).
[11:26:31.872] INFO: Test took 4429ms.
[11:26:31.987] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:31.987] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683926
[11:26:31.987] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:26:31.988] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:32.041] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:32.706] INFO: Expecting 41600 events.
[11:26:36.461] INFO: 41600 events read in total (3228ms).
[11:26:36.465] INFO: Test took 4425ms.
[11:26:36.582] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:36.582] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 682441
[11:26:36.582] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:26:36.583] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:36.639] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:37.305] INFO: Expecting 41600 events.
[11:26:41.058] INFO: 41600 events read in total (3226ms).
[11:26:41.062] INFO: Test took 4423ms.
[11:26:41.180] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:41.180] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 681464
[11:26:41.180] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:26:41.181] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:41.236] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:41.904] INFO: Expecting 41600 events.
[11:26:45.664] INFO: 41600 events read in total (3233ms).
[11:26:45.669] INFO: Test took 4433ms.
[11:26:45.785] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:45.785] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683345
[11:26:45.785] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:26:45.786] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:45.842] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:46.509] INFO: Expecting 41600 events.
[11:26:50.265] INFO: 41600 events read in total (3229ms).
[11:26:50.270] INFO: Test took 4428ms.
[11:26:50.384] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:50.384] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 681959
[11:26:50.384] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:26:50.385] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:50.439] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:51.104] INFO: Expecting 41600 events.
[11:26:54.854] INFO: 41600 events read in total (3224ms).
[11:26:54.859] INFO: Test took 4420ms.
[11:26:54.971] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:54.971] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683265
[11:26:54.971] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:26:54.971] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:55.026] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:26:55.690] INFO: Expecting 41600 events.
[11:26:59.424] INFO: 41600 events read in total (3207ms).
[11:26:59.428] INFO: Test took 4402ms.
[11:26:59.545] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:26:59.545] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 681786
[11:26:59.545] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:26:59.546] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:26:59.602] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:00.269] INFO: Expecting 41600 events.
[11:27:03.849] INFO: 41600 events read in total (3053ms).
[11:27:03.854] INFO: Test took 4252ms.
[11:27:03.965] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:03.965] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 674651
[11:27:03.965] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:27:03.966] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:27:04.242] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 183 eff = 0.999135
[11:27:04.243] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 137 eff = 0.999543
[11:27:04.243] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 170 eff = 0.999014
[11:27:04.243] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 164 eff = 0.998005
[11:27:04.243] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 161 eff = 0.997668
[11:27:04.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 172 eff = 0.997933
[11:27:04.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 177 eff = 0.997548
[11:27:04.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 174 eff = 0.997861
[11:27:04.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 183 eff = 0.998197
[11:27:04.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 168 eff = 0.997764
[11:27:04.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 180 eff = 0.997909
[11:27:04.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 209 eff = 0.997957
[11:27:04.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 214 eff = 0.998173
[11:27:04.246] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 199 eff = 0.998798
[11:27:04.246] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 150 eff = 0.999567
[11:27:04.246] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 199 eff = 0.999303
[11:27:22.506] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:27:22.506] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:27:22.506] INFO: ----------------------------------------------------------------------
[11:27:22.506] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:27:22.506] INFO: ----------------------------------------------------------------------
[11:27:22.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:27:22.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:27:22.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:27:22.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:27:22.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:27:22.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:27:22.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:27:22.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:27:22.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:27:22.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:27:22.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:27:22.507] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:27:22.525] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:23.196] INFO: Expecting 208000 events.
[11:27:38.245] INFO: 208000 events read in total (14522ms).
[11:27:38.261] INFO: Test took 15736ms.
[11:27:38.866] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:38.866] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3121433
[11:27:38.866] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:27:38.867] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:27:39.330] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 1
[11:27:39.330] INFO: number of red-efficiency pixels: 175 133 238 392 428 412 508 470 340 448 445 388 419 275 117 144
[11:27:39.330] INFO: number of X-ray hits detected: 132404 88839 147984 221887 237625 238308 240330 225134 227275 240725 241484 227683 235559 154173 87956 107509
[11:27:39.330] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:27:39.330] INFO: number of Vcal hits detected: 207818 207861 207743 207578 207496 207567 207407 207491 207636 207505 207513 207587 207543 207666 207881 207804
[11:27:39.330] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9
[11:27:39.330] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9
[11:27:39.330] INFO: X-ray hit rate [MHz/cm2]: 38.8 26.0 43.4 65.0 69.6 69.8 70.4 66.0 66.6 70.6 70.8 66.7 69.0 45.2 25.8 31.5
[11:27:39.330] INFO: PixTestHighRate::doXPixelAlive() done
[11:27:39.374] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:27:39.374] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:27:39.374] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:27:39.374] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:27:39.374] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:27:39.374] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:27:39.375] INFO: PixTest:: pg_setup set to default.
[11:27:55.218] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:27:55.218] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[11:28:00.090] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:28:00.091] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 396.3mA
[11:28:00.092] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 475.9mA
[11:28:00.092] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[11:28:00.092] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:28:00.092] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:28:00.092] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:28:00.092] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:28:00.092] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:28:00.092] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:28:00.092] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:28:00.092] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:28:00.092] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:28:00.092] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:28:00.185] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:28:00.185] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:28:00.198] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:28:00.198] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:28:00.199] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:28:00.199] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:28:00.199] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:28:00.199] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:28:00.201] QUIET: Connection to board 58 closed.
[11:28:00.281] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[11:20:12.530] INFO: *** Welcome to pxar ***
[11:20:12.530] INFO: *** Today: 2016/09/21
[11:20:12.553] INFO: *** Version: v1.9.0-818-g96727
[11:20:12.553] INFO: readRocDacs: data/mq939/dacParameters35_C0.dat .. data/mq939/dacParameters35_C15.dat
[11:20:12.554] INFO: readTbmDacs: data/mq939/tbmParameters_C0a.dat .. data/mq939/tbmParameters_C0b.dat
[11:20:12.554] INFO: readMaskFile: data/mq939/defaultMaskFile.dat
[11:20:12.554] INFO: readTrimFile: data/mq939/trimParameters35_C0.dat .. data/mq939/trimParameters35_C15.dat
[11:20:12.707] INFO: clk: 4
[11:20:12.707] INFO: ctr: 4
[11:20:12.707] INFO: sda: 19
[11:20:12.707] INFO: tin: 9
[11:20:12.707] INFO: level: 15
[11:20:12.707] INFO: triggerdelay: 0
[11:20:12.707] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:20:12.707] INFO: Log level: DEBUG
[11:20:12.719] QUIET: Connection to board DTB_WRPRHI opened.
[11:20:12.722] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[11:20:12.725] INFO: RPC call hashes of host and DTB match: 398089610
[11:20:14.328] INFO: DUT info:
[11:20:14.328] INFO: The DUT currently contains the following objects:
[11:20:14.328] INFO: 2 TBM Cores tbm08c (2 ON)
[11:20:14.328] INFO: TBM Core alpha (0): 7 registers set
[11:20:14.328] INFO: TBM Core beta (1): 7 registers set
[11:20:14.328] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:20:14.328] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.328] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:20:14.329] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:20:14.330] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:20:14.331] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:20:14.332] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:20:14.332] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:20:14.332] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:20:14.332] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:20:14.332] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:20:14.332] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:20:14.332] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:20:14.332] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:20:14.332] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:20:14.332] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:20:14.332] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:20:14.334] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32727040
[11:20:14.334] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x17e80e0
[11:20:14.334] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x158a370
[11:20:14.334] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f155dd94010
[11:20:14.334] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f1563fff510
[11:20:14.334] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32735232 fPxarMemory = 0x7f155dd94010
[11:20:14.335] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 393.1mA
[11:20:14.337] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.1mA
[11:20:14.337] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[11:20:14.853] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1bd6f70
[11:20:14.919] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:20:14.919] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:20:14.919] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:20:14.925] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:20:14.925] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:20:14.925] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:20:14.936] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:20:14.936] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:20:14.950] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:20:14.950] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:20:14.968] INFO: PixTestCmd::init()
[11:20:14.983] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:20:14.984] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:20:14.984] INFO: readGainPedestalParameters data/mq939/phCalibrationFitErr35_C0.dat .. data/mq939/phCalibrationFitErr35_C15.dat
[11:20:15.236] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:20:15.236] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:20:15.253] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:20:15.258] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:20:15.259] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:20:15.259] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:20:15.283] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:20:15.283] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:20:15.283] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:20:15.283] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:20:15.283] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:20:15.289] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:20:15.289] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:20:15.310] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:20:15.310] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:20:15.310] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:20:15.310] INFO: readReadbackCal: data/mq939/readbackCal_C0.dat .. data/mq939/readbackCal_C15.dat
[11:20:15.319] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:20:15.328] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:20:15.328] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:20:15.328] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:20:15.328] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:20:15.346] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:20:15.346] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:20:37.613] INFO: ######################################################################
[11:20:37.613] INFO: PixTestAlive::doTest()
[11:20:37.613] INFO: ######################################################################
[11:20:37.617] INFO: ----------------------------------------------------------------------
[11:20:37.617] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:20:37.617] INFO: ----------------------------------------------------------------------
[11:20:37.619] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:20:37.998] INFO: Expecting 41600 events.
[11:20:42.399] INFO: 41600 events read in total (3683ms).
[11:20:42.545] INFO: Test took 4926ms.
[11:20:42.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:42.556] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[11:20:42.556] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:20:42.832] INFO: PixTestAlive::aliveTest() done
[11:20:42.832] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 1
[11:20:42.832] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 39 33 68 77 91 85 99 73 66 81 88 76 81 34 24 35
[11:20:42.858] INFO: ----------------------------------------------------------------------
[11:20:42.858] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:20:42.858] INFO: ----------------------------------------------------------------------
[11:20:42.860] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:20:43.236] INFO: Expecting 41600 events.
[11:20:46.357] INFO: 41600 events read in total (2405ms).
[11:20:46.358] INFO: Test took 3498ms.
[11:20:46.358] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:46.358] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:20:46.358] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:20:46.359] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:20:46.779] INFO: PixTestAlive::maskTest() done
[11:20:46.779] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:20:46.802] INFO: ----------------------------------------------------------------------
[11:20:46.802] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:20:46.802] INFO: ----------------------------------------------------------------------
[11:20:46.804] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:20:47.182] INFO: Expecting 41600 events.
[11:20:51.566] INFO: 41600 events read in total (3669ms).
[11:20:51.567] INFO: Test took 4763ms.
[11:20:51.578] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:20:51.578] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[11:20:51.578] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:20:51.948] INFO: PixTestAlive::addressDecodingTest() done
[11:20:51.948] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:20:51.949] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[11:21:03.898] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:21:03.898] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C6_V0 -> PixelAlive_mod
[11:21:10.922] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:21:10.922] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:21:10.922] INFO: ----------------------------------------------------------------------
[11:21:10.922] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:21:10.922] INFO: ----------------------------------------------------------------------
[11:21:11.061] INFO: Expecting 768 events.
[11:21:12.196] INFO: 768 events read in total (419ms).
[11:21:12.196] INFO: Test took 1268ms.
[11:21:12.202] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:13.000] INFO: Expecting 41600 events.
[11:21:16.781] INFO: 41600 events read in total (3255ms).
[11:21:16.786] INFO: Test took 4584ms.
[11:21:16.936] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:16.936] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 861449
[11:21:16.936] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:21:16.937] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:16.999] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:17.664] INFO: Expecting 41600 events.
[11:21:21.567] INFO: 41600 events read in total (3376ms).
[11:21:21.572] INFO: Test took 4573ms.
[11:21:21.715] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:21.715] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 871160
[11:21:21.715] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:21:21.715] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:21.782] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:22.448] INFO: Expecting 41600 events.
[11:21:26.407] INFO: 41600 events read in total (3432ms).
[11:21:26.412] INFO: Test took 4630ms.
[11:21:26.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:26.556] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874517
[11:21:26.556] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:21:26.557] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:26.625] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:27.293] INFO: Expecting 41600 events.
[11:21:31.245] INFO: 41600 events read in total (3426ms).
[11:21:31.250] INFO: Test took 4625ms.
[11:21:31.393] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:31.393] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874253
[11:21:31.393] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:21:31.394] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:31.462] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:32.127] INFO: Expecting 41600 events.
[11:21:36.109] INFO: 41600 events read in total (3455ms).
[11:21:36.115] INFO: Test took 4653ms.
[11:21:36.256] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:36.256] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 875260
[11:21:36.256] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:21:36.256] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:36.323] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:36.988] INFO: Expecting 41600 events.
[11:21:40.970] INFO: 41600 events read in total (3455ms).
[11:21:40.975] INFO: Test took 4652ms.
[11:21:41.118] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:41.118] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874818
[11:21:41.118] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:21:41.119] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:41.187] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:41.854] INFO: Expecting 41600 events.
[11:21:45.848] INFO: 41600 events read in total (3467ms).
[11:21:45.854] INFO: Test took 4667ms.
[11:21:45.996] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:45.996] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 876336
[11:21:45.996] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:21:45.996] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:46.063] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:46.728] INFO: Expecting 41600 events.
[11:21:50.728] INFO: 41600 events read in total (3473ms).
[11:21:50.733] INFO: Test took 4670ms.
[11:21:50.874] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:50.874] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 877564
[11:21:50.874] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:21:50.874] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:50.942] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:51.608] INFO: Expecting 41600 events.
[11:21:55.615] INFO: 41600 events read in total (3481ms).
[11:21:55.621] INFO: Test took 4679ms.
[11:21:55.763] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:21:55.763] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 875906
[11:21:55.763] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:21:55.764] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:21:55.831] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:21:56.496] INFO: Expecting 41600 events.
[11:22:00.443] INFO: 41600 events read in total (3420ms).
[11:22:00.448] INFO: Test took 4617ms.
[11:22:00.590] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:00.590] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 877396
[11:22:00.590] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:22:00.591] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:00.657] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:01.328] INFO: Expecting 41600 events.
[11:22:05.316] INFO: 41600 events read in total (3461ms).
[11:22:05.321] INFO: Test took 4664ms.
[11:22:05.463] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:05.463] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874116
[11:22:05.463] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:22:05.463] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:05.530] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:06.195] INFO: Expecting 41600 events.
[11:22:10.195] INFO: 41600 events read in total (3473ms).
[11:22:10.201] INFO: Test took 4671ms.
[11:22:10.344] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:10.344] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 876447
[11:22:10.345] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:22:10.345] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:10.414] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:11.081] INFO: Expecting 41600 events.
[11:22:15.062] INFO: 41600 events read in total (3455ms).
[11:22:15.068] INFO: Test took 4654ms.
[11:22:15.215] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:15.215] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 876591
[11:22:15.215] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:22:15.215] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:15.283] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:15.948] INFO: Expecting 41600 events.
[11:22:19.945] INFO: 41600 events read in total (3470ms).
[11:22:19.950] INFO: Test took 4666ms.
[11:22:20.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:20.094] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 876995
[11:22:20.094] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:22:20.094] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:20.164] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:20.829] INFO: Expecting 41600 events.
[11:22:24.824] INFO: 41600 events read in total (3469ms).
[11:22:24.829] INFO: Test took 4665ms.
[11:22:24.971] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:24.971] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 875966
[11:22:24.971] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:22:24.972] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:25.039] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:25.704] INFO: Expecting 41600 events.
[11:22:29.696] INFO: 41600 events read in total (3466ms).
[11:22:29.701] INFO: Test took 4662ms.
[11:22:29.847] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:29.847] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 875590
[11:22:29.847] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:22:29.847] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:29.915] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:30.580] INFO: Expecting 41600 events.
[11:22:34.591] INFO: 41600 events read in total (3485ms).
[11:22:34.596] INFO: Test took 4681ms.
[11:22:34.740] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:34.740] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 875511
[11:22:34.740] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:22:34.741] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:34.810] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:35.478] INFO: Expecting 41600 events.
[11:22:39.476] INFO: 41600 events read in total (3472ms).
[11:22:39.481] INFO: Test took 4671ms.
[11:22:39.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:39.626] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 877633
[11:22:39.626] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:22:39.626] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:39.695] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:40.363] INFO: Expecting 41600 events.
[11:22:44.349] INFO: 41600 events read in total (3460ms).
[11:22:44.355] INFO: Test took 4660ms.
[11:22:44.500] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:44.500] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 874003
[11:22:44.500] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:22:44.501] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:44.569] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:22:45.236] INFO: Expecting 41600 events.
[11:22:49.059] INFO: 41600 events read in total (3296ms).
[11:22:49.064] INFO: Test took 4495ms.
[11:22:49.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:22:49.204] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 867940
[11:22:49.205] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:22:49.205] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:22:49.492] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 184 eff = 0.998846
[11:22:49.493] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 148 eff = 0.999447
[11:22:49.493] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 170 eff = 0.998173
[11:22:49.493] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 164 eff = 0.996971
[11:22:49.493] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 161 eff = 0.995529
[11:22:49.493] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 172 eff = 0.995721
[11:22:49.493] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 177 eff = 0.995889
[11:22:49.493] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 176 eff = 0.995793
[11:22:49.494] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 182 eff = 0.996995
[11:22:49.494] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 171 eff = 0.995793
[11:22:49.494] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 185 eff = 0.996154
[11:22:49.494] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 208 eff = 0.996034
[11:22:49.494] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 214 eff = 0.996178
[11:22:49.494] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 193 eff = 0.998245
[11:22:49.495] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 162 eff = 0.999399
[11:22:49.495] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 196 eff = 0.999063
[11:23:25.538] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:23:25.538] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:23:25.538] INFO: ----------------------------------------------------------------------
[11:23:25.538] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:23:25.538] INFO: ----------------------------------------------------------------------
[11:23:25.538] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:23:25.539] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:23:25.539] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:23:25.539] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:23:25.539] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:23:25.539] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:23:25.539] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:23:25.539] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:23:25.539] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:23:25.539] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:23:25.539] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:23:25.539] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:23:25.556] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:23:26.222] INFO: Expecting 208000 events.
[11:23:42.978] INFO: 208000 events read in total (16229ms).
[11:23:42.998] INFO: Test took 17442ms.
[11:23:43.703] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:43.703] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4069124
[11:23:43.703] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:23:43.704] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:23:44.202] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 1
[11:23:44.202] INFO: number of red-efficiency pixels: 254 177 396 644 761 722 778 675 634 721 774 631 687 433 165 220
[11:23:44.202] INFO: number of X-ray hits detected: 173524 116306 193716 290664 311440 310863 314557 296027 296409 316951 316470 298119 308713 201301 116072 141434
[11:23:44.202] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:23:44.202] INFO: number of Vcal hits detected: 207735 207813 207563 207281 207070 207165 207078 207232 207310 207173 207095 207282 207224 207519 207826 207722
[11:23:44.202] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.6 99.6 99.7 99.7 99.6 99.6 99.7 99.7 99.8 99.9 99.9
[11:23:44.202] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.6 99.6 99.6 99.7 99.6 99.6 99.7 99.6 99.8 99.9 99.9
[11:23:44.202] INFO: X-ray hit rate [MHz/cm2]: 50.9 34.1 56.8 85.2 91.3 91.1 92.2 86.8 86.9 92.9 92.8 87.4 90.5 59.0 34.0 41.5
[11:23:44.202] INFO: PixTestHighRate::doXPixelAlive() done
[11:23:44.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:23:44.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:23:44.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:23:44.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:23:44.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:23:44.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:23:44.248] INFO: PixTest:: pg_setup set to default.
[11:24:24.378] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:24:24.378] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C9_V0 -> highRate_xraymap_mod
[11:24:30.082] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:24:30.083] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 396.3mA
[11:24:30.084] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 475.9mA
[11:24:30.084] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[11:24:30.084] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:24:30.084] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:24:30.084] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:24:30.084] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:24:30.084] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:24:30.084] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:24:30.084] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:24:30.084] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:24:30.084] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:24:30.084] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:24:30.176] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:24:30.176] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:24:30.190] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:24:30.190] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:24:30.190] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:24:30.190] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:24:30.190] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:24:30.190] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:24:30.193] QUIET: Connection to board 58 closed.
[11:24:30.272] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master