[12:08:57.773] INFO: *** Welcome to pxar ***
[12:08:57.773] INFO: *** Today: 2016/09/30
[12:08:57.796] INFO: *** Version: v1.9.0-818-g96727
[12:08:57.796] INFO: readRocDacs: data/mq914/dacParameters35_C0.dat .. data/mq914/dacParameters35_C15.dat
[12:08:57.797] INFO: readTbmDacs: data/mq914/tbmParameters_C0a.dat .. data/mq914/tbmParameters_C0b.dat
[12:08:57.797] INFO: readMaskFile: data/mq914/defaultMaskFile.dat
[12:08:57.797] INFO: readTrimFile: data/mq914/trimParameters35_C0.dat .. data/mq914/trimParameters35_C15.dat
[12:08:57.949] INFO: clk: 4
[12:08:57.949] INFO: ctr: 4
[12:08:57.949] INFO: sda: 19
[12:08:57.949] INFO: tin: 9
[12:08:57.949] INFO: level: 15
[12:08:57.949] INFO: triggerdelay: 0
[12:08:57.949] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:08:57.949] INFO: Log level: DEBUG
[12:08:57.963] QUIET: Connection to board DTB_WRPRHI opened.
[12:08:57.966] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[12:08:57.969] INFO: RPC call hashes of host and DTB match: 398089610
[12:08:59.570] INFO: DUT info:
[12:08:59.570] INFO: The DUT currently contains the following objects:
[12:08:59.570] INFO: 2 TBM Cores tbm08c (2 ON)
[12:08:59.570] INFO: TBM Core alpha (0): 7 registers set
[12:08:59.570] INFO: TBM Core beta (1): 7 registers set
[12:08:59.570] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:08:59.570] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.570] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:08:59.571] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:08:59.572] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:08:59.573] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:08:59.574] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:08:59.576] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34832384
[12:08:59.576] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1fe20e0
[12:08:59.576] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1d84370
[12:08:59.576] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fb80dd94010
[12:08:59.576] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fb813fff510
[12:08:59.576] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34840576 fPxarMemory = 0x7fb80dd94010
[12:08:59.578] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[12:08:59.579] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[12:08:59.579] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[12:09:00.077] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x23d1030
[12:09:00.139] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:09:00.139] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:09:00.139] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:09:00.145] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:09:00.145] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:09:00.145] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:09:00.155] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:09:00.155] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:09:00.165] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:09:00.165] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:09:00.190] INFO: PixTestCmd::init()
[12:09:00.200] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:09:00.200] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:09:00.200] INFO: readGainPedestalParameters data/mq914/phCalibrationFitErr35_C0.dat .. data/mq914/phCalibrationFitErr35_C15.dat
[12:09:00.445] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:09:00.445] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:09:00.459] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:09:00.465] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:09:00.465] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:09:00.465] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:09:00.490] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:09:00.490] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:09:00.490] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:09:00.490] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:09:00.490] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:09:00.498] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:09:00.498] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:09:00.521] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:09:00.521] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:09:00.521] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:09:00.521] INFO: readReadbackCal: data/mq914/readbackCal_C0.dat .. data/mq914/readbackCal_C15.dat
[12:09:00.533] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:09:00.548] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:09:00.548] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:09:00.548] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:09:00.548] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:09:00.564] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:09:00.564] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:09:08.822] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[12:09:10.814] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[12:09:10.814] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[12:09:10.814] INFO: ----------------------------------------------------------------------
[12:09:10.814] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[12:09:10.814] INFO: ----------------------------------------------------------------------
[12:09:11.788] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[12:09:24.192] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[12:09:24.196] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:09:51.557] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1222127 events.
[12:09:57.708] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1222127, pixels seen in all events: 5586840
[12:09:57.898] INFO: Resuming triggers.
[12:10:10.303] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[12:10:10.345] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:10:37.573] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1222132 events.
[12:10:43.638] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1222132, pixels seen in all events: 5585770
[12:10:43.811] INFO: Resuming triggers.
[12:10:56.219] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[12:10:56.324] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:11:23.389] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1222452 events.
[12:11:29.521] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1222452, pixels seen in all events: 5582476
[12:11:29.696] INFO: Resuming triggers.
[12:11:42.109] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[12:11:42.154] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:12:09.386] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1222849 events.
[12:12:15.507] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1222849, pixels seen in all events: 5577068
[12:12:15.610] INFO: Resuming triggers.
[12:12:26.060] INFO: data taking finished, elapsed time: 60 seconds.
[12:12:26.256] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:12:48.965] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1029440 events.
[12:12:54.074] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1029440, pixels seen in all events: 4694424
[12:12:54.160] INFO: PixTest:: pg_setup set to default.
[12:12:54.259] INFO: PixTestXray::doPhRun() done
[12:13:41.062] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:13:41.063] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.1mA
[12:13:41.064] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[12:13:41.064] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:13:41.064] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:13:41.064] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:13:41.064] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:13:41.064] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:13:41.064] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:13:41.064] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:13:41.064] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:13:41.064] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:13:41.064] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:13:41.064] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:13:41.064] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:13:41.064] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:13:41.064] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:13:41.065] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:13:41.065] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:13:41.065] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:13:41.065] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:13:41.065] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:13:41.345] QUIET: Connection to board 58 closed.
[12:13:41.425] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[12:14:02.382] INFO: *** Welcome to pxar ***
[12:14:02.382] INFO: *** Today: 2016/09/30
[12:14:02.410] INFO: *** Version: v1.9.0-818-g96727
[12:14:02.410] INFO: readRocDacs: data/mq914/dacParameters35_C0.dat .. data/mq914/dacParameters35_C15.dat
[12:14:02.411] INFO: readTbmDacs: data/mq914/tbmParameters_C0a.dat .. data/mq914/tbmParameters_C0b.dat
[12:14:02.411] INFO: readMaskFile: data/mq914/defaultMaskFile.dat
[12:14:02.411] INFO: readTrimFile: data/mq914/trimParameters35_C0.dat .. data/mq914/trimParameters35_C15.dat
[12:14:02.561] INFO: clk: 4
[12:14:02.561] INFO: ctr: 4
[12:14:02.561] INFO: sda: 19
[12:14:02.561] INFO: tin: 9
[12:14:02.561] INFO: level: 15
[12:14:02.561] INFO: triggerdelay: 0
[12:14:02.561] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:14:02.561] INFO: Log level: DEBUG
[12:14:02.572] QUIET: Connection to board DTB_WRPRHI opened.
[12:14:02.577] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[12:14:02.579] INFO: RPC call hashes of host and DTB match: 398089610
[12:14:04.183] INFO: DUT info:
[12:14:04.183] INFO: The DUT currently contains the following objects:
[12:14:04.183] INFO: 2 TBM Cores tbm08c (2 ON)
[12:14:04.183] INFO: TBM Core alpha (0): 7 registers set
[12:14:04.183] INFO: TBM Core beta (1): 7 registers set
[12:14:04.183] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:14:04.183] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.183] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:14:04.184] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:14:04.185] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:14:04.186] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:14:04.187] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:14:04.189] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33169408
[12:14:04.189] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x29f80e0
[12:14:04.189] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x279a370
[12:14:04.189] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f4429d94010
[12:14:04.189] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f442ffff510
[12:14:04.189] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33177600 fPxarMemory = 0x7f4429d94010
[12:14:04.190] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[12:14:04.192] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 464.7mA
[12:14:04.192] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[12:14:04.687] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2de6fd0
[12:14:04.748] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:14:04.749] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:14:04.749] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:14:04.754] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:14:04.754] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:14:04.754] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:14:04.765] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:14:04.765] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:14:04.786] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:14:04.786] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:14:04.803] INFO: PixTestCmd::init()
[12:14:04.821] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:14:04.821] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:14:04.821] INFO: readGainPedestalParameters data/mq914/phCalibrationFitErr35_C0.dat .. data/mq914/phCalibrationFitErr35_C15.dat
[12:14:05.068] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:14:05.069] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:14:05.082] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:14:05.089] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:14:05.089] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:14:05.089] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:14:05.115] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:14:05.115] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:14:05.115] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:14:05.115] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:14:05.115] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:14:05.122] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:14:05.122] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:14:05.141] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:14:05.141] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:14:05.141] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:14:05.141] INFO: readReadbackCal: data/mq914/readbackCal_C0.dat .. data/mq914/readbackCal_C15.dat
[12:14:05.150] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:14:05.159] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:14:05.159] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:14:05.159] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:14:05.159] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:14:05.176] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:14:05.176] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:14:16.174] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[12:14:16.822] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[12:14:16.822] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[12:14:16.822] INFO: ----------------------------------------------------------------------
[12:14:16.822] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[12:14:16.822] INFO: ----------------------------------------------------------------------
[12:14:17.796] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[12:14:25.439] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[12:14:25.443] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:14:50.504] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 753105 events.
[12:15:02.485] DEBUG: <PixTestXray.cc/processData:L823> # events read: 753105, pixels seen in all events: 11216360
[12:15:02.730] INFO: Resuming triggers.
[12:15:10.380] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[12:15:10.419] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:15:35.331] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 753544 events.
[12:15:47.382] DEBUG: <PixTestXray.cc/processData:L823> # events read: 753544, pixels seen in all events: 11208904
[12:15:47.584] INFO: Resuming triggers.
[12:15:55.231] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[12:15:55.297] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:16:20.274] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 753337 events.
[12:16:32.270] DEBUG: <PixTestXray.cc/processData:L823> # events read: 753337, pixels seen in all events: 11211606
[12:16:32.505] INFO: Resuming triggers.
[12:16:40.151] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[12:16:40.191] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:17:05.156] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 753226 events.
[12:17:17.150] DEBUG: <PixTestXray.cc/processData:L823> # events read: 753226, pixels seen in all events: 11212583
[12:17:17.378] INFO: Resuming triggers.
[12:17:25.019] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[12:17:25.061] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:17:50.097] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 752807 events.
[12:18:02.123] DEBUG: <PixTestXray.cc/processData:L823> # events read: 752807, pixels seen in all events: 11218514
[12:18:02.352] INFO: Resuming triggers.
[12:18:09.999] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[12:18:10.030] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:18:34.986] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 753275 events.
[12:18:47.051] DEBUG: <PixTestXray.cc/processData:L823> # events read: 753275, pixels seen in all events: 11211962
[12:18:47.280] INFO: Resuming triggers.
[12:18:54.929] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[12:18:54.973] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:19:19.936] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 753521 events.
[12:19:31.933] DEBUG: <PixTestXray.cc/processData:L823> # events read: 753521, pixels seen in all events: 11210244
[12:19:32.160] INFO: Resuming triggers.
[12:19:38.717] INFO: data taking finished, elapsed time: 60 seconds.
[12:19:38.913] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[12:20:00.302] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 645923 events.
[12:20:10.583] DEBUG: <PixTestXray.cc/processData:L823> # events read: 645923, pixels seen in all events: 9614660
[12:20:10.782] INFO: PixTest:: pg_setup set to default.
[12:20:10.827] INFO: PixTestXray::doPhRun() done
[12:20:44.870] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:20:44.871] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.1mA
[12:20:44.871] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[12:20:44.872] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[12:20:44.872] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:20:44.872] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:20:44.872] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:20:44.872] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:20:44.872] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:20:44.872] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:20:44.872] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:20:44.872] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:20:44.872] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:20:44.872] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:20:44.872] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:20:44.872] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:20:44.872] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:20:44.873] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:20:44.873] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:20:44.873] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:20:44.873] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:20:44.873] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:20:45.185] QUIET: Connection to board 58 closed.
[12:20:45.265] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[12:02:05.695] INFO: *** Welcome to pxar ***
[12:02:05.695] INFO: *** Today: 2016/09/30
[12:02:05.718] INFO: *** Version: v1.9.0-818-g96727
[12:02:05.718] INFO: readRocDacs: data/mq914/dacParameters35_C0.dat .. data/mq914/dacParameters35_C15.dat
[12:02:05.719] INFO: readTbmDacs: data/mq914/tbmParameters_C0a.dat .. data/mq914/tbmParameters_C0b.dat
[12:02:05.720] INFO: readMaskFile: data/mq914/defaultMaskFile.dat
[12:02:05.720] INFO: readTrimFile: data/mq914/trimParameters35_C0.dat .. data/mq914/trimParameters35_C15.dat
[12:02:05.868] INFO: clk: 4
[12:02:05.868] INFO: ctr: 4
[12:02:05.868] INFO: sda: 19
[12:02:05.869] INFO: tin: 9
[12:02:05.869] INFO: level: 15
[12:02:05.869] INFO: triggerdelay: 0
[12:02:05.869] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[12:02:05.869] INFO: Log level: DEBUG
[12:02:05.880] QUIET: Connection to board DTB_WRPRHI opened.
[12:02:05.883] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[12:02:05.886] INFO: RPC call hashes of host and DTB match: 398089610
[12:02:07.488] INFO: DUT info:
[12:02:07.488] INFO: The DUT currently contains the following objects:
[12:02:07.488] INFO: 2 TBM Cores tbm08c (2 ON)
[12:02:07.488] INFO: TBM Core alpha (0): 7 registers set
[12:02:07.488] INFO: TBM Core beta (1): 7 registers set
[12:02:07.488] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:02:07.488] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.488] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[12:02:07.489] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[12:02:07.490] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[12:02:07.491] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[12:02:07.492] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[12:02:07.492] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[12:02:07.492] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[12:02:07.494] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34816000
[12:02:07.494] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x130b0e0
[12:02:07.494] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x10ad370
[12:02:07.494] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fb1d5d94010
[12:02:07.494] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fb1dbfff510
[12:02:07.494] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34824192 fPxarMemory = 0x7fb1d5d94010
[12:02:07.495] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.9mA
[12:02:07.496] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[12:02:07.496] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[12:02:07.996] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x16f9f70
[12:02:08.063] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[12:02:08.063] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[12:02:08.063] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[12:02:08.069] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[12:02:08.069] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[12:02:08.069] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[12:02:08.080] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[12:02:08.080] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[12:02:08.095] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[12:02:08.095] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[12:02:08.126] INFO: PixTestCmd::init()
[12:02:08.144] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[12:02:08.144] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[12:02:08.144] INFO: readGainPedestalParameters data/mq914/phCalibrationFitErr35_C0.dat .. data/mq914/phCalibrationFitErr35_C15.dat
[12:02:08.393] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[12:02:08.393] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[12:02:08.408] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[12:02:08.415] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[12:02:08.415] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[12:02:08.415] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[12:02:08.440] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[12:02:08.440] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[12:02:08.440] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[12:02:08.440] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[12:02:08.440] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[12:02:08.447] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[12:02:08.447] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[12:02:08.468] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[12:02:08.468] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[12:02:08.468] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[12:02:08.468] INFO: readReadbackCal: data/mq914/readbackCal_C0.dat .. data/mq914/readbackCal_C15.dat
[12:02:08.478] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[12:02:08.489] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[12:02:08.489] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[12:02:08.489] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[12:02:08.489] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[12:02:08.506] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[12:02:08.506] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[12:02:45.737] INFO: ######################################################################
[12:02:45.737] INFO: PixTestAlive::doTest()
[12:02:45.737] INFO: ######################################################################
[12:02:45.740] INFO: ----------------------------------------------------------------------
[12:02:45.740] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:02:45.740] INFO: ----------------------------------------------------------------------
[12:02:45.743] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:02:46.123] INFO: Expecting 41600 events.
[12:02:50.520] INFO: 41600 events read in total (3678ms).
[12:02:50.668] INFO: Test took 4925ms.
[12:02:50.678] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:02:50.678] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:02:50.678] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[12:02:50.959] INFO: PixTestAlive::aliveTest() done
[12:02:50.959] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:02:50.959] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 24 18 29 30 43 42 42 40 35 47 40 31 50 34 22 18
[12:02:50.982] INFO: ----------------------------------------------------------------------
[12:02:50.982] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:02:50.982] INFO: ----------------------------------------------------------------------
[12:02:50.984] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:02:51.361] INFO: Expecting 41600 events.
[12:02:54.480] INFO: 41600 events read in total (2404ms).
[12:02:54.480] INFO: Test took 3496ms.
[12:02:54.480] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:02:54.480] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[12:02:54.480] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[12:02:54.481] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:02:54.903] INFO: PixTestAlive::maskTest() done
[12:02:54.903] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:02:54.925] INFO: ----------------------------------------------------------------------
[12:02:54.925] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:02:54.925] INFO: ----------------------------------------------------------------------
[12:02:54.927] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:02:55.306] INFO: Expecting 41600 events.
[12:02:59.687] INFO: 41600 events read in total (3665ms).
[12:02:59.688] INFO: Test took 4761ms.
[12:02:59.698] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:02:59.698] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[12:02:59.698] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[12:03:00.067] INFO: PixTestAlive::addressDecodingTest() done
[12:03:00.067] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:03:00.068] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[12:03:05.974] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:03:05.974] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[12:03:09.494] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[12:03:09.494] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[12:03:09.494] INFO: ----------------------------------------------------------------------
[12:03:09.494] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:03:09.494] INFO: ----------------------------------------------------------------------
[12:03:09.633] INFO: Expecting 768 events.
[12:03:10.767] INFO: 768 events read in total (418ms).
[12:03:10.767] INFO: Test took 1268ms.
[12:03:10.773] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:03:11.571] INFO: Expecting 41600 events.
[12:03:14.943] INFO: 41600 events read in total (2845ms).
[12:03:14.948] INFO: Test took 4175ms.
[12:03:15.033] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:15.033] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 473696
[12:03:15.033] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[12:03:15.033] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:03:15.072] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:03:15.741] INFO: Expecting 41600 events.
[12:03:19.223] INFO: 41600 events read in total (2955ms).
[12:03:19.227] INFO: Test took 4155ms.
[12:03:19.307] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:19.307] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 480722
[12:03:19.307] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[12:03:19.307] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:03:19.347] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:03:20.011] INFO: Expecting 41600 events.
[12:03:23.527] INFO: 41600 events read in total (2989ms).
[12:03:23.531] INFO: Test took 4184ms.
[12:03:23.611] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:23.611] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483742
[12:03:23.611] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[12:03:23.611] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:03:23.652] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:03:24.318] INFO: Expecting 41600 events.
[12:03:27.847] INFO: 41600 events read in total (3002ms).
[12:03:27.850] INFO: Test took 4198ms.
[12:03:27.930] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:27.930] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483229
[12:03:27.930] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[12:03:27.930] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:03:27.970] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:03:28.635] INFO: Expecting 41600 events.
[12:03:32.161] INFO: 41600 events read in total (2999ms).
[12:03:32.165] INFO: Test took 4195ms.
[12:03:32.245] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:32.246] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483910
[12:03:32.246] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[12:03:32.246] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:03:32.287] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:03:32.954] INFO: Expecting 41600 events.
[12:03:36.484] INFO: 41600 events read in total (3003ms).
[12:03:36.488] INFO: Test took 4201ms.
[12:03:36.567] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:36.567] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483369
[12:03:36.567] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[12:03:36.568] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:03:36.607] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:03:37.272] INFO: Expecting 41600 events.
[12:03:40.804] INFO: 41600 events read in total (3005ms).
[12:03:40.807] INFO: Test took 4200ms.
[12:03:40.887] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:40.887] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484508
[12:03:40.887] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[12:03:40.887] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:03:40.928] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:03:41.593] INFO: Expecting 41600 events.
[12:03:45.120] INFO: 41600 events read in total (3001ms).
[12:03:45.124] INFO: Test took 4196ms.
[12:03:45.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:45.204] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484128
[12:03:45.204] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[12:03:45.204] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:03:45.244] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:03:45.909] INFO: Expecting 41600 events.
[12:03:49.385] INFO: 41600 events read in total (2950ms).
[12:03:49.389] INFO: Test took 4145ms.
[12:03:49.469] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:49.469] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 485470
[12:03:49.469] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[12:03:49.470] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:03:49.510] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:03:50.176] INFO: Expecting 41600 events.
[12:03:53.683] INFO: 41600 events read in total (2980ms).
[12:03:53.686] INFO: Test took 4177ms.
[12:03:53.766] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:53.766] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484101
[12:03:53.766] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[12:03:53.767] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:03:53.806] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:03:54.471] INFO: Expecting 41600 events.
[12:03:57.987] INFO: 41600 events read in total (2989ms).
[12:03:57.991] INFO: Test took 4185ms.
[12:03:58.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:03:58.070] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482841
[12:03:58.070] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[12:03:58.071] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:03:58.110] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:03:58.777] INFO: Expecting 41600 events.
[12:04:02.320] INFO: 41600 events read in total (3016ms).
[12:04:02.324] INFO: Test took 4214ms.
[12:04:02.404] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:02.404] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484417
[12:04:02.404] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[12:04:02.404] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:04:02.445] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:04:03.110] INFO: Expecting 41600 events.
[12:04:06.651] INFO: 41600 events read in total (3014ms).
[12:04:06.655] INFO: Test took 4210ms.
[12:04:06.734] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:06.734] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484340
[12:04:06.734] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:04:06.734] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:04:06.774] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:04:07.439] INFO: Expecting 41600 events.
[12:04:10.957] INFO: 41600 events read in total (2991ms).
[12:04:10.960] INFO: Test took 4186ms.
[12:04:11.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:11.039] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 485058
[12:04:11.039] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:04:11.040] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:04:11.080] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:04:11.745] INFO: Expecting 41600 events.
[12:04:15.283] INFO: 41600 events read in total (3011ms).
[12:04:15.287] INFO: Test took 4207ms.
[12:04:15.367] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:15.367] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483806
[12:04:15.367] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:04:15.367] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:04:15.407] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:04:16.073] INFO: Expecting 41600 events.
[12:04:19.609] INFO: 41600 events read in total (3009ms).
[12:04:19.613] INFO: Test took 4206ms.
[12:04:19.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:19.691] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 482723
[12:04:19.691] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:04:19.691] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:04:19.732] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:04:20.397] INFO: Expecting 41600 events.
[12:04:23.933] INFO: 41600 events read in total (3009ms).
[12:04:23.937] INFO: Test took 4205ms.
[12:04:24.019] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:24.019] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 484600
[12:04:24.019] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:04:24.019] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:04:24.061] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:04:24.728] INFO: Expecting 41600 events.
[12:04:28.270] INFO: 41600 events read in total (3016ms).
[12:04:28.273] INFO: Test took 4212ms.
[12:04:28.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:28.356] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483738
[12:04:28.356] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:04:28.356] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:04:28.398] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:04:29.065] INFO: Expecting 41600 events.
[12:04:32.587] INFO: 41600 events read in total (2995ms).
[12:04:32.591] INFO: Test took 4193ms.
[12:04:32.672] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:32.672] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 483387
[12:04:32.672] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:04:32.672] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:04:32.713] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:04:33.383] INFO: Expecting 41600 events.
[12:04:36.746] INFO: 41600 events read in total (2837ms).
[12:04:36.750] INFO: Test took 4037ms.
[12:04:36.829] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:36.830] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 478798
[12:04:36.830] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:04:36.830] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:04:37.090] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 146 eff = 0.999519
[12:04:37.091] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 165 eff = 0.999639
[12:04:37.091] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 146 eff = 0.999399
[12:04:37.091] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 153 eff = 0.998894
[12:04:37.091] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 147 eff = 0.99887
[12:04:37.092] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 137 eff = 0.998774
[12:04:37.092] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 185 eff = 0.998918
[12:04:37.092] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 169 eff = 0.99899
[12:04:37.092] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 154 eff = 0.998966
[12:04:37.093] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 141 eff = 0.998726
[12:04:37.093] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 127 eff = 0.998798
[12:04:37.093] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 148 eff = 0.998966
[12:04:37.093] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 155 eff = 0.998918
[12:04:37.093] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 156 eff = 0.999327
[12:04:37.093] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 130 eff = 0.999736
[12:04:37.094] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 155 eff = 0.999688
[12:04:39.398] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:04:39.398] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:04:39.398] INFO: ----------------------------------------------------------------------
[12:04:39.398] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:04:39.398] INFO: ----------------------------------------------------------------------
[12:04:39.398] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:04:39.398] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:04:39.398] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:04:39.398] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:04:39.398] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:04:39.398] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:04:39.398] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:04:39.398] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:04:39.398] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:04:39.398] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:04:39.398] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:04:39.398] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:04:39.416] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:04:40.085] INFO: Expecting 208000 events.
[12:04:53.616] INFO: 208000 events read in total (13004ms).
[12:04:53.629] INFO: Test took 14213ms.
[12:04:54.033] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:54.033] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2137853
[12:04:54.033] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:04:54.034] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:04:54.406] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:04:54.406] INFO: number of red-efficiency pixels: 154 74 142 263 267 314 222 194 265 263 316 238 241 147 99 100
[12:04:54.406] INFO: number of X-ray hits detected: 95769 63651 102903 154773 163599 164876 158166 148252 143570 167606 165562 153772 153879 101166 60473 73276
[12:04:54.406] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:04:54.406] INFO: number of Vcal hits detected: 207844 207923 207850 207728 207721 207675 207763 207799 207717 207722 207669 207751 207752 207846 207897 207896
[12:04:54.406] 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.8 99.9 99.9 99.9 100.0 100.0
[12:04:54.406] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 100.0 100.0
[12:04:54.406] INFO: X-ray hit rate [MHz/cm2]: 28.1 18.7 30.2 45.4 48.0 48.3 46.4 43.5 42.1 49.1 48.5 45.1 45.1 29.7 17.7 21.5
[12:04:54.406] INFO: PixTestHighRate::doXPixelAlive() done
[12:04:54.453] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:04:54.453] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:04:54.453] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:04:54.453] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:04:54.453] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:04:54.453] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:04:54.453] INFO: PixTest:: pg_setup set to default.
[12:05:25.854] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:05:25.854] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C7_V0 -> highRate_xraymap_mod
[12:05:27.990] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:05:27.990] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.1mA
[12:05:27.991] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[12:05:27.991] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[12:05:27.991] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:05:27.991] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:05:27.991] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:05:27.992] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:05:27.992] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:05:27.992] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:05:27.992] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:05:27.992] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:05:27.992] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:05:27.992] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:05:28.087] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:05:28.087] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:05:28.101] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:05:28.101] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:05:28.101] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:05:28.101] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:05:28.101] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:05:28.101] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:05:28.104] QUIET: Connection to board 58 closed.
[12:05:28.184] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[11:58:18.992] INFO: *** Welcome to pxar ***
[11:58:18.992] INFO: *** Today: 2016/09/30
[11:58:19.019] INFO: *** Version: v1.9.0-818-g96727
[11:58:19.019] INFO: readRocDacs: data/mq914/dacParameters35_C0.dat .. data/mq914/dacParameters35_C15.dat
[11:58:19.020] INFO: readTbmDacs: data/mq914/tbmParameters_C0a.dat .. data/mq914/tbmParameters_C0b.dat
[11:58:19.021] INFO: readMaskFile: data/mq914/defaultMaskFile.dat
[11:58:19.021] INFO: readTrimFile: data/mq914/trimParameters35_C0.dat .. data/mq914/trimParameters35_C15.dat
[11:58:19.173] INFO: clk: 4
[11:58:19.173] INFO: ctr: 4
[11:58:19.173] INFO: sda: 19
[11:58:19.173] INFO: tin: 9
[11:58:19.173] INFO: level: 15
[11:58:19.173] INFO: triggerdelay: 0
[11:58:19.174] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:58:19.174] INFO: Log level: DEBUG
[11:58:19.185] QUIET: Connection to board DTB_WRPRHI opened.
[11:58:19.188] 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:58:19.191] INFO: RPC call hashes of host and DTB match: 398089610
[11:58:20.794] INFO: DUT info:
[11:58:20.794] INFO: The DUT currently contains the following objects:
[11:58:20.794] INFO: 2 TBM Cores tbm08c (2 ON)
[11:58:20.794] INFO: TBM Core alpha (0): 7 registers set
[11:58:20.794] INFO: TBM Core beta (1): 7 registers set
[11:58:20.794] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:58:20.794] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.794] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.795] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:58:20.795] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:58:20.796] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:58:20.797] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:58:20.798] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:58:20.800] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33427456
[11:58:20.800] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2af10e0
[11:58:20.800] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2893370
[11:58:20.800] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f3625d94010
[11:58:20.800] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f362bfff510
[11:58:20.800] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33435648 fPxarMemory = 0x7f3625d94010
[11:58:20.802] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[11:58:20.803] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[11:58:20.803] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:58:21.303] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2edff80
[11:58:21.372] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:58:21.372] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:58:21.372] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:58:21.377] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:58:21.377] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:58:21.378] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:58:21.387] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:58:21.387] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:58:21.395] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:58:21.395] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:58:21.417] INFO: PixTestCmd::init()
[11:58:21.431] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:58:21.431] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:58:21.431] INFO: readGainPedestalParameters data/mq914/phCalibrationFitErr35_C0.dat .. data/mq914/phCalibrationFitErr35_C15.dat
[11:58:21.678] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:58:21.678] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:58:21.692] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:58:21.699] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:58:21.700] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:58:21.700] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:58:21.722] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:58:21.722] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:58:21.722] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:58:21.722] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:58:21.722] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:58:21.729] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:58:21.729] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:58:21.751] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:58:21.751] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:58:21.751] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:58:21.751] INFO: readReadbackCal: data/mq914/readbackCal_C0.dat .. data/mq914/readbackCal_C15.dat
[11:58:21.761] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:58:21.769] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:58:21.769] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:58:21.769] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:58:21.769] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:58:21.787] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:58:21.787] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:58:30.705] INFO: ######################################################################
[11:58:30.705] INFO: PixTestAlive::doTest()
[11:58:30.705] INFO: ######################################################################
[11:58:30.708] INFO: ----------------------------------------------------------------------
[11:58:30.708] 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:58:30.708] INFO: ----------------------------------------------------------------------
[11:58:30.711] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:58:31.089] INFO: Expecting 41600 events.
[11:58:35.494] INFO: 41600 events read in total (3687ms).
[11:58:35.642] INFO: Test took 4931ms.
[11:58:35.654] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:58:35.654] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[11:58:35.654] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:58:35.931] INFO: PixTestAlive::aliveTest() done
[11:58:35.931] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:58:35.931] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 32 15 33 66 47 79 46 44 61 57 62 54 59 33 24 35
[11:58:35.957] INFO: ----------------------------------------------------------------------
[11:58:35.957] 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:58:35.957] INFO: ----------------------------------------------------------------------
[11:58:35.959] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:58:36.336] INFO: Expecting 41600 events.
[11:58:39.428] INFO: 41600 events read in total (2377ms).
[11:58:39.428] INFO: Test took 3469ms.
[11:58:39.428] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:58:39.428] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:58:39.428] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:58:39.429] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:58:39.851] INFO: PixTestAlive::maskTest() done
[11:58:39.851] 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:58:39.873] INFO: ----------------------------------------------------------------------
[11:58:39.873] 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:58:39.873] INFO: ----------------------------------------------------------------------
[11:58:39.875] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:58:40.253] INFO: Expecting 41600 events.
[11:58:44.633] INFO: 41600 events read in total (3665ms).
[11:58:44.634] INFO: Test took 4759ms.
[11:58:44.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:58:44.645] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[11:58:44.645] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:58:45.019] INFO: PixTestAlive::addressDecodingTest() done
[11:58:45.019] 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:58:45.019] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[11:58:55.998] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:58:55.998] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C6_V0 -> PixelAlive_mod
[11:58:59.358] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:58:59.358] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:58:59.358] INFO: ----------------------------------------------------------------------
[11:58:59.358] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:58:59.358] INFO: ----------------------------------------------------------------------
[11:58:59.497] INFO: Expecting 768 events.
[11:59:00.629] INFO: 768 events read in total (417ms).
[11:59:00.629] INFO: Test took 1266ms.
[11:59:00.635] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:01.432] INFO: Expecting 41600 events.
[11:59:04.998] INFO: 41600 events read in total (3039ms).
[11:59:05.003] INFO: Test took 4368ms.
[11:59:05.122] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:05.122] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 676667
[11:59:05.122] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:59:05.123] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:59:05.175] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:05.841] INFO: Expecting 41600 events.
[11:59:09.541] INFO: 41600 events read in total (3173ms).
[11:59:09.546] INFO: Test took 4371ms.
[11:59:09.662] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:09.663] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 689484
[11:59:09.663] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:59:09.663] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:59:09.716] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:10.381] INFO: Expecting 41600 events.
[11:59:14.138] INFO: 41600 events read in total (3231ms).
[11:59:14.142] INFO: Test took 4426ms.
[11:59:14.259] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:14.259] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692976
[11:59:14.259] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:59:14.260] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:59:14.313] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:14.978] INFO: Expecting 41600 events.
[11:59:18.743] INFO: 41600 events read in total (3238ms).
[11:59:18.747] INFO: Test took 4434ms.
[11:59:18.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:18.864] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 693895
[11:59:18.864] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:59:18.864] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:59:18.917] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:19.582] INFO: Expecting 41600 events.
[11:59:23.343] INFO: 41600 events read in total (3234ms).
[11:59:23.347] INFO: Test took 4430ms.
[11:59:23.464] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:23.465] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692844
[11:59:23.465] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:59:23.465] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:59:23.518] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:24.183] INFO: Expecting 41600 events.
[11:59:27.944] INFO: 41600 events read in total (3234ms).
[11:59:27.949] INFO: Test took 4431ms.
[11:59:28.062] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:28.062] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692133
[11:59:28.062] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:59:28.062] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:59:28.117] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:28.783] INFO: Expecting 41600 events.
[11:59:32.564] INFO: 41600 events read in total (3255ms).
[11:59:32.568] INFO: Test took 4451ms.
[11:59:32.687] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:32.687] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692319
[11:59:32.687] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:59:32.688] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:59:32.742] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:33.409] INFO: Expecting 41600 events.
[11:59:37.189] INFO: 41600 events read in total (3253ms).
[11:59:37.193] INFO: Test took 4451ms.
[11:59:37.311] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:37.311] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 694738
[11:59:37.311] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:59:37.311] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:59:37.367] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:38.033] INFO: Expecting 41600 events.
[11:59:41.814] INFO: 41600 events read in total (3254ms).
[11:59:41.818] INFO: Test took 4451ms.
[11:59:41.934] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:41.934] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 694364
[11:59:41.934] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:59:41.934] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:59:41.988] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:42.653] INFO: Expecting 41600 events.
[11:59:46.431] INFO: 41600 events read in total (3252ms).
[11:59:46.436] INFO: Test took 4448ms.
[11:59:46.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:46.552] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 693154
[11:59:46.552] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:59:46.553] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:59:46.607] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:47.272] INFO: Expecting 41600 events.
[11:59:51.051] INFO: 41600 events read in total (3253ms).
[11:59:51.056] INFO: Test took 4449ms.
[11:59:51.172] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:51.172] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692581
[11:59:51.172] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:59:51.172] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:59:51.226] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:51.891] INFO: Expecting 41600 events.
[11:59:55.674] INFO: 41600 events read in total (3256ms).
[11:59:55.679] INFO: Test took 4453ms.
[11:59:55.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:59:55.795] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 693634
[11:59:55.795] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:59:55.796] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:59:55.850] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:59:56.515] INFO: Expecting 41600 events.
[12:00:00.296] INFO: 41600 events read in total (3255ms).
[12:00:00.301] INFO: Test took 4451ms.
[12:00:00.416] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:00:00.416] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692607
[12:00:00.416] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[12:00:00.417] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:00:00.470] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:00:01.135] INFO: Expecting 41600 events.
[12:00:04.916] INFO: 41600 events read in total (3254ms).
[12:00:04.921] INFO: Test took 4451ms.
[12:00:05.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:00:05.039] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 693947
[12:00:05.039] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[12:00:05.040] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:00:05.095] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:00:05.762] INFO: Expecting 41600 events.
[12:00:09.538] INFO: 41600 events read in total (3249ms).
[12:00:09.543] INFO: Test took 4448ms.
[12:00:09.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:00:09.661] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692842
[12:00:09.661] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[12:00:09.662] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:00:09.717] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:00:10.384] INFO: Expecting 41600 events.
[12:00:14.159] INFO: 41600 events read in total (3249ms).
[12:00:14.163] INFO: Test took 4446ms.
[12:00:14.281] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:00:14.281] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692716
[12:00:14.281] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[12:00:14.282] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:00:14.337] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:00:15.005] INFO: Expecting 41600 events.
[12:00:18.785] INFO: 41600 events read in total (3254ms).
[12:00:18.789] INFO: Test took 4452ms.
[12:00:18.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:00:18.905] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 693601
[12:00:18.905] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[12:00:18.905] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:00:18.960] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:00:19.626] INFO: Expecting 41600 events.
[12:00:23.401] INFO: 41600 events read in total (3249ms).
[12:00:23.406] INFO: Test took 4446ms.
[12:00:23.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:00:23.522] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 692993
[12:00:23.522] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[12:00:23.523] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:00:23.577] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:00:24.242] INFO: Expecting 41600 events.
[12:00:27.996] INFO: 41600 events read in total (3228ms).
[12:00:28.001] INFO: Test took 4424ms.
[12:00:28.118] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:00:28.118] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 691707
[12:00:28.118] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[12:00:28.119] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:00:28.172] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:00:28.837] INFO: Expecting 41600 events.
[12:00:32.433] INFO: 41600 events read in total (3069ms).
[12:00:32.437] INFO: Test took 4265ms.
[12:00:32.550] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:00:32.550] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 686073
[12:00:32.550] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[12:00:32.551] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:00:32.826] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 162 eff = 0.998966
[12:00:32.826] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 164 eff = 0.999495
[12:00:32.827] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 147 eff = 0.998798
[12:00:32.827] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 164 eff = 0.998005
[12:00:32.827] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 155 eff = 0.997644
[12:00:32.827] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 146 eff = 0.997163
[12:00:32.827] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 182 eff = 0.997764
[12:00:32.827] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 169 eff = 0.997837
[12:00:32.827] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 164 eff = 0.997813
[12:00:32.828] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 146 eff = 0.997356
[12:00:32.828] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 152 eff = 0.997476
[12:00:32.828] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 154 eff = 0.998029
[12:00:32.828] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 152 eff = 0.997933
[12:00:32.828] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 156 eff = 0.998654
[12:00:32.829] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 141 eff = 0.999447
[12:00:32.829] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 129 eff = 0.999327
[12:01:08.190] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[12:01:08.190] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[12:01:08.190] INFO: ----------------------------------------------------------------------
[12:01:08.190] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:01:08.190] INFO: ----------------------------------------------------------------------
[12:01:08.190] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[12:01:08.190] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[12:01:08.190] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[12:01:08.190] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[12:01:08.190] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[12:01:08.190] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[12:01:08.190] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[12:01:08.190] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[12:01:08.190] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[12:01:08.190] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[12:01:08.190] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[12:01:08.190] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[12:01:08.208] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[12:01:08.876] INFO: Expecting 208000 events.
[12:01:24.092] INFO: 208000 events read in total (14689ms).
[12:01:24.109] INFO: Test took 15901ms.
[12:01:24.689] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:01:24.689] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3169354
[12:01:24.690] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[12:01:24.690] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[12:01:25.128] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:01:25.129] INFO: number of red-efficiency pixels: 196 138 280 446 475 501 468 435 416 476 523 405 431 285 121 197
[12:01:25.129] INFO: number of X-ray hits detected: 144393 96126 153638 231546 245134 247592 237557 222716 215008 250671 248419 228799 229401 151193 90387 110214
[12:01:25.129] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:01:25.129] INFO: number of Vcal hits detected: 207779 207855 207700 207516 207474 207458 207509 207540 207552 207477 207417 207558 207535 207688 207873 207798
[12:01:25.129] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.9 99.9 99.9
[12:01:25.129] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.8 99.8 99.8 99.7 99.7 99.8 99.8 99.9 99.9 99.9
[12:01:25.129] INFO: X-ray hit rate [MHz/cm2]: 42.3 28.2 45.0 67.9 71.9 72.6 69.6 65.3 63.0 73.5 72.8 67.1 67.2 44.3 26.5 32.3
[12:01:25.129] INFO: PixTestHighRate::doXPixelAlive() done
[12:01:25.174] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[12:01:25.174] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[12:01:25.174] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[12:01:25.174] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[12:01:25.174] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[12:01:25.174] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[12:01:25.174] INFO: PixTest:: pg_setup set to default.
[12:01:47.894] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[12:01:47.894] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C9_V0 -> highRate_xraymap_mod
[12:01:50.550] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[12:01:50.550] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.1mA
[12:01:50.551] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[12:01:50.551] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[12:01:50.551] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[12:01:50.551] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[12:01:50.551] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[12:01:50.551] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[12:01:50.552] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[12:01:50.552] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[12:01:50.552] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[12:01:50.552] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[12:01:50.552] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[12:01:50.552] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[12:01:50.645] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[12:01:50.645] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[12:01:50.659] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[12:01:50.659] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[12:01:50.659] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[12:01:50.659] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[12:01:50.659] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[12:01:50.659] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[12:01:50.661] QUIET: Connection to board 58 closed.
[12:01:50.741] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[11:54:03.732] INFO: *** Welcome to pxar ***
[11:54:03.732] INFO: *** Today: 2016/09/30
[11:54:03.756] INFO: *** Version: v1.9.0-818-g96727
[11:54:03.756] INFO: readRocDacs: data/mq914/dacParameters35_C0.dat .. data/mq914/dacParameters35_C15.dat
[11:54:03.757] INFO: readTbmDacs: data/mq914/tbmParameters_C0a.dat .. data/mq914/tbmParameters_C0b.dat
[11:54:03.758] INFO: readMaskFile: data/mq914/defaultMaskFile.dat
[11:54:03.758] INFO: readTrimFile: data/mq914/trimParameters35_C0.dat .. data/mq914/trimParameters35_C15.dat
[11:54:03.910] INFO: clk: 4
[11:54:03.910] INFO: ctr: 4
[11:54:03.910] INFO: sda: 19
[11:54:03.910] INFO: tin: 9
[11:54:03.910] INFO: level: 15
[11:54:03.910] INFO: triggerdelay: 0
[11:54:03.910] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:54:03.910] INFO: Log level: DEBUG
[11:54:03.922] QUIET: Connection to board DTB_WRPRHI opened.
[11:54:03.925] 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:54:03.928] INFO: RPC call hashes of host and DTB match: 398089610
[11:54:05.529] INFO: DUT info:
[11:54:05.529] INFO: The DUT currently contains the following objects:
[11:54:05.529] INFO: 2 TBM Cores tbm08c (2 ON)
[11:54:05.529] INFO: TBM Core alpha (0): 7 registers set
[11:54:05.529] INFO: TBM Core beta (1): 7 registers set
[11:54:05.529] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:54:05.529] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.529] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.529] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.529] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.529] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.529] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.530] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.530] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.530] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.530] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.530] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.530] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.530] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.530] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.530] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.530] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:54:05.530] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:54:05.531] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:54:05.532] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:54:05.533] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:54:05.536] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33497088
[11:54:05.536] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x13150e0
[11:54:05.536] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x10b7370
[11:54:05.536] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7efde1393010
[11:54:05.536] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7efde75fe510
[11:54:05.536] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33505280 fPxarMemory = 0x7efde1393010
[11:54:05.537] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 385.1mA
[11:54:05.538] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[11:54:05.538] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:54:06.039] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1703f70
[11:54:06.108] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:54:06.108] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:54:06.109] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:54:06.114] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:54:06.114] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:54:06.114] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:54:06.125] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:54:06.125] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:54:06.147] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:54:06.147] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:54:06.168] INFO: PixTestCmd::init()
[11:54:06.184] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:54:06.184] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:54:06.184] INFO: readGainPedestalParameters data/mq914/phCalibrationFitErr35_C0.dat .. data/mq914/phCalibrationFitErr35_C15.dat
[11:54:06.431] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:54:06.432] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:54:06.446] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:54:06.452] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:54:06.452] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:54:06.452] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:54:06.476] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:54:06.477] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:54:06.477] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:54:06.477] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:54:06.477] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:54:06.483] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:54:06.483] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:54:06.504] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:54:06.504] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:54:06.504] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:54:06.504] INFO: readReadbackCal: data/mq914/readbackCal_C0.dat .. data/mq914/readbackCal_C15.dat
[11:54:06.514] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:54:06.524] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:54:06.524] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:54:06.524] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:54:06.524] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:54:06.542] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:54:06.542] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:54:13.113] INFO: ######################################################################
[11:54:13.113] INFO: PixTestAlive::doTest()
[11:54:13.113] INFO: ######################################################################
[11:54:13.116] INFO: ----------------------------------------------------------------------
[11:54:13.116] 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:54:13.116] INFO: ----------------------------------------------------------------------
[11:54:13.119] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:54:13.498] INFO: Expecting 41600 events.
[11:54:17.891] INFO: 41600 events read in total (3675ms).
[11:54:18.038] INFO: Test took 4919ms.
[11:54:18.048] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:54:18.048] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[11:54:18.048] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:54:18.327] INFO: PixTestAlive::aliveTest() done
[11:54:18.327] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:54:18.327] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 40 31 52 79 82 92 78 62 87 82 70 89 80 53 26 44
[11:54:18.349] INFO: ----------------------------------------------------------------------
[11:54:18.349] 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:54:18.349] INFO: ----------------------------------------------------------------------
[11:54:18.352] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:54:18.730] INFO: Expecting 41600 events.
[11:54:21.844] INFO: 41600 events read in total (2398ms).
[11:54:21.844] INFO: Test took 3492ms.
[11:54:21.844] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:54:21.844] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:54:21.844] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:54:21.845] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:54:22.267] INFO: PixTestAlive::maskTest() done
[11:54:22.267] 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:54:22.290] INFO: ----------------------------------------------------------------------
[11:54:22.290] 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:54:22.290] INFO: ----------------------------------------------------------------------
[11:54:22.292] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:54:22.671] INFO: Expecting 41600 events.
[11:54:27.038] INFO: 41600 events read in total (3652ms).
[11:54:27.039] INFO: Test took 4747ms.
[11:54:27.049] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:54:27.049] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[11:54:27.049] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:54:27.420] INFO: PixTestAlive::addressDecodingTest() done
[11:54:27.420] 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:54:27.420] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[11:54:47.414] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:54:47.414] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C2_V0 -> PixelAlive_mod
[11:54:53.237] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:54:53.238] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:54:53.238] INFO: ----------------------------------------------------------------------
[11:54:53.238] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:54:53.238] INFO: ----------------------------------------------------------------------
[11:54:53.377] INFO: Expecting 768 events.
[11:54:54.511] INFO: 768 events read in total (419ms).
[11:54:54.511] INFO: Test took 1268ms.
[11:54:54.517] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:54:55.315] INFO: Expecting 41600 events.
[11:54:59.190] INFO: 41600 events read in total (3348ms).
[11:54:59.195] INFO: Test took 4678ms.
[11:54:59.349] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:54:59.349] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 896211
[11:54:59.349] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:54:59.350] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:54:59.415] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:00.080] INFO: Expecting 41600 events.
[11:55:04.084] INFO: 41600 events read in total (3477ms).
[11:55:04.089] INFO: Test took 4674ms.
[11:55:04.239] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:04.239] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 908968
[11:55:04.239] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:55:04.239] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:55:04.310] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:04.977] INFO: Expecting 41600 events.
[11:55:09.036] INFO: 41600 events read in total (3533ms).
[11:55:09.042] INFO: Test took 4732ms.
[11:55:09.188] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:09.188] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 911916
[11:55:09.188] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:55:09.188] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:55:09.259] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:09.924] INFO: Expecting 41600 events.
[11:55:13.983] INFO: 41600 events read in total (3533ms).
[11:55:13.988] INFO: Test took 4729ms.
[11:55:14.137] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:14.137] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 910307
[11:55:14.137] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:55:14.137] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:55:14.208] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:14.875] INFO: Expecting 41600 events.
[11:55:18.957] INFO: 41600 events read in total (3556ms).
[11:55:18.962] INFO: Test took 4754ms.
[11:55:19.110] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:19.110] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912840
[11:55:19.110] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:55:19.111] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:55:19.180] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:19.845] INFO: Expecting 41600 events.
[11:55:23.909] INFO: 41600 events read in total (3537ms).
[11:55:23.914] INFO: Test took 4734ms.
[11:55:24.061] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:24.062] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 911864
[11:55:24.062] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:55:24.062] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:55:24.131] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:24.796] INFO: Expecting 41600 events.
[11:55:28.888] INFO: 41600 events read in total (3565ms).
[11:55:28.894] INFO: Test took 4763ms.
[11:55:29.043] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:29.043] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912903
[11:55:29.043] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:55:29.043] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:55:29.112] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:29.777] INFO: Expecting 41600 events.
[11:55:33.839] INFO: 41600 events read in total (3535ms).
[11:55:33.844] INFO: Test took 4732ms.
[11:55:33.991] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:33.991] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912367
[11:55:33.991] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:55:33.991] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:55:34.061] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:34.726] INFO: Expecting 41600 events.
[11:55:38.843] INFO: 41600 events read in total (3590ms).
[11:55:38.849] INFO: Test took 4788ms.
[11:55:39.000] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:39.000] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 914005
[11:55:39.000] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:55:39.000] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:55:39.069] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:39.735] INFO: Expecting 41600 events.
[11:55:43.829] INFO: 41600 events read in total (3567ms).
[11:55:43.834] INFO: Test took 4765ms.
[11:55:43.985] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:43.986] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 913343
[11:55:43.986] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:55:43.986] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:55:44.056] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:44.722] INFO: Expecting 41600 events.
[11:55:48.823] INFO: 41600 events read in total (3574ms).
[11:55:48.828] INFO: Test took 4772ms.
[11:55:48.976] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:48.976] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 911077
[11:55:48.976] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:55:48.977] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:55:49.046] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:49.711] INFO: Expecting 41600 events.
[11:55:53.798] INFO: 41600 events read in total (3560ms).
[11:55:53.804] INFO: Test took 4758ms.
[11:55:53.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:53.953] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 913000
[11:55:53.953] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:55:53.953] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:55:54.023] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:54.688] INFO: Expecting 41600 events.
[11:55:58.796] INFO: 41600 events read in total (3581ms).
[11:55:58.802] INFO: Test took 4779ms.
[11:55:58.949] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:55:58.949] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 911870
[11:55:58.949] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:55:58.950] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:55:59.019] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:55:59.686] INFO: Expecting 41600 events.
[11:56:03.760] INFO: 41600 events read in total (3548ms).
[11:56:03.765] INFO: Test took 4746ms.
[11:56:03.913] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:56:03.913] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 911080
[11:56:03.913] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:56:03.913] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:56:03.982] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:56:04.648] INFO: Expecting 41600 events.
[11:56:08.770] INFO: 41600 events read in total (3595ms).
[11:56:08.775] INFO: Test took 4793ms.
[11:56:08.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:56:08.923] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 911525
[11:56:08.923] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:56:08.923] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:56:08.993] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:56:09.659] INFO: Expecting 41600 events.
[11:56:13.810] INFO: 41600 events read in total (3624ms).
[11:56:13.815] INFO: Test took 4822ms.
[11:56:13.965] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:56:13.965] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912383
[11:56:13.965] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:56:13.966] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:56:14.044] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:56:14.711] INFO: Expecting 41600 events.
[11:56:18.788] INFO: 41600 events read in total (3550ms).
[11:56:18.794] INFO: Test took 4750ms.
[11:56:18.945] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:56:18.945] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912143
[11:56:18.945] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:56:18.945] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:56:19.016] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:56:19.683] INFO: Expecting 41600 events.
[11:56:23.784] INFO: 41600 events read in total (3574ms).
[11:56:23.790] INFO: Test took 4774ms.
[11:56:23.937] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:56:23.937] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912079
[11:56:23.937] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:56:23.938] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:56:24.006] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:56:24.671] INFO: Expecting 41600 events.
[11:56:28.746] INFO: 41600 events read in total (3548ms).
[11:56:28.751] INFO: Test took 4745ms.
[11:56:28.900] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:56:28.900] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 908096
[11:56:28.900] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:56:28.900] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:56:28.970] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:56:29.635] INFO: Expecting 41600 events.
[11:56:33.532] INFO: 41600 events read in total (3371ms).
[11:56:33.538] INFO: Test took 4568ms.
[11:56:33.684] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:56:33.684] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 904939
[11:56:33.684] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:56:33.685] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:56:33.976] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 161 eff = 0.998149
[11:56:33.976] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 168 eff = 0.999183
[11:56:33.976] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 149 eff = 0.997091
[11:56:33.977] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 163 eff = 0.995649
[11:56:33.977] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 152 eff = 0.994736
[11:56:33.977] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 146 eff = 0.994784
[11:56:33.977] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 182 eff = 0.99524
[11:56:33.978] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 168 eff = 0.995673
[11:56:33.978] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 163 eff = 0.996202
[11:56:33.978] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 146 eff = 0.995024
[11:56:33.978] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 151 eff = 0.994519
[11:56:33.979] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 150 eff = 0.995409
[11:56:33.979] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 155 eff = 0.996178
[11:56:33.979] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 154 eff = 0.998101
[11:56:33.979] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 148 eff = 0.999183
[11:56:33.980] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 136 eff = 0.99887
[11:57:03.526] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:57:03.526] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:57:03.526] INFO: ----------------------------------------------------------------------
[11:57:03.526] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:57:03.526] INFO: ----------------------------------------------------------------------
[11:57:03.526] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:57:03.526] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:57:03.526] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:57:03.526] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:57:03.526] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:57:03.526] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:57:03.526] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:57:03.526] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:57:03.526] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:57:03.526] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:57:03.526] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:57:03.526] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:57:03.545] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:57:04.214] INFO: Expecting 208000 events.
[11:57:21.290] INFO: 208000 events read in total (16550ms).
[11:57:21.312] INFO: Test took 17767ms.
[11:57:22.179] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:57:22.179] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4239702
[11:57:22.179] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:57:22.180] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:57:22.694] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:57:22.694] INFO: number of red-efficiency pixels: 334 231 480 753 910 921 750 799 733 959 935 831 745 403 216 251
[11:57:22.694] INFO: number of X-ray hits detected: 194259 128930 205683 311576 329532 333254 319384 298686 290455 337397 332597 308855 308450 203579 121437 149068
[11:57:22.694] 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:57:22.694] INFO: number of Vcal hits detected: 207629 207759 207440 207139 206953 206885 207120 207073 207142 206861 206854 207026 207141 207535 207771 207741
[11:57:22.694] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.5 99.5 99.6 99.6 99.6 99.5 99.5 99.6 99.6 99.8 99.9 99.9
[11:57:22.694] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.5 99.6 99.6 99.6 99.5 99.4 99.5 99.6 99.8 99.9 99.9
[11:57:22.694] INFO: X-ray hit rate [MHz/cm2]: 56.9 37.8 60.3 91.3 96.6 97.7 93.6 87.5 85.1 98.9 97.5 90.5 90.4 59.7 35.6 43.7
[11:57:22.694] INFO: PixTestHighRate::doXPixelAlive() done
[11:57:22.739] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:57:22.739] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:57:22.740] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:57:22.740] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:57:22.740] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:57:22.740] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:57:22.740] INFO: PixTest:: pg_setup set to default.
[11:57:35.182] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[11:57:35.182] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C13_V0 -> highRate_xraymap_mod
[11:57:39.134] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:57:39.134] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.1mA
[11:57:39.135] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[11:57:39.135] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[11:57:39.135] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:57:39.135] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:57:39.135] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:57:39.135] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:57:39.135] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:57:39.136] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:57:39.136] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:57:39.136] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:57:39.136] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:57:39.136] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:57:39.229] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:57:39.229] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:57:39.243] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:57:39.243] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:57:39.243] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:57:39.243] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:57:39.243] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:57:39.243] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:57:39.245] QUIET: Connection to board 58 closed.
[11:57:39.325] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries