[15:48:07.095] INFO: *** Welcome to pxar ***
[15:48:07.095] INFO: *** Today: 2016/06/07
[15:48:07.122] INFO: *** Version: v1.9.0-814-g7497
[15:48:07.122] INFO: readRocDacs: data/mi212/dacParameters35_C0.dat .. data/mi212/dacParameters35_C15.dat
[15:48:07.123] INFO: readTbmDacs: data/mi212/tbmParameters_C0a.dat .. data/mi212/tbmParameters_C0b.dat
[15:48:07.123] INFO: readMaskFile: data/mi212/defaultMaskFile.dat
[15:48:07.123] INFO: readTrimFile: data/mi212/trimParameters35_C0.dat .. data/mi212/trimParameters35_C15.dat
[15:48:07.273] INFO: clk: 4
[15:48:07.273] INFO: ctr: 4
[15:48:07.273] INFO: sda: 19
[15:48:07.273] INFO: tin: 9
[15:48:07.273] INFO: level: 15
[15:48:07.273] INFO: triggerdelay: 0
[15:48:07.273] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:48:07.273] INFO: Log level: DEBUG
[15:48:07.285] QUIET: Connection to board DTB_WRPRHI opened.
[15:48:07.288] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:48:07.291] INFO: RPC call hashes of host and DTB match: 398089610
[15:48:08.895] INFO: DUT info:
[15:48:08.895] INFO: The DUT currently contains the following objects:
[15:48:08.895] INFO: 2 TBM Cores tbm08c (2 ON)
[15:48:08.895] INFO: TBM Core alpha (0): 7 registers set
[15:48:08.895] INFO: TBM Core beta (1): 7 registers set
[15:48:08.895] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:48:08.895] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.895] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:48:08.896] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:48:08.897] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:48:08.898] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:48:08.899] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:48:08.899] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:48:08.899] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:48:08.899] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:48:08.899] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:48:08.899] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:48:08.899] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:48:08.899] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:48:08.899] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:48:08.899] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:48:08.899] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:48:08.901] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35344384
[15:48:08.901] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2a72bd0
[15:48:08.901] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2814e60
[15:48:08.901] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fb545d94010
[15:48:08.901] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fb54bfff510
[15:48:08.901] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35352576 fPxarMemory = 0x7fb545d94010
[15:48:08.902] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[15:48:08.904] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[15:48:08.904] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[15:48:09.401] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2e5d940
[15:48:09.465] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:48:09.466] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:48:09.466] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:48:09.471] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:48:09.471] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:48:09.471] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:48:09.483] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:48:09.483] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:48:09.496] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:48:09.496] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:48:09.509] INFO: PixTestCmd::init()
[15:48:09.519] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:48:09.519] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:48:09.519] INFO: readGainPedestalParameters data/mi212/phCalibrationFitErr35_C0.dat .. data/mi212/phCalibrationFitErr35_C15.dat
[15:48:09.769] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:48:09.769] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:48:09.783] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:48:09.789] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:48:09.789] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:48:09.789] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:48:09.809] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:48:09.809] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:48:09.809] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:48:09.809] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:48:09.809] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:48:09.815] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:48:09.815] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:48:09.829] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:48:09.830] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:48:09.830] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:48:09.830] INFO: readReadbackCal: data/mi212/readbackCal_C0.dat .. data/mi212/readbackCal_C15.dat
[15:48:09.840] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:48:09.848] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:48:09.848] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:48:09.848] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:48:09.848] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:48:09.868] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:48:09.868] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:48:15.963] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[15:48:18.827] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 6
[15:48:20.667] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[15:48:22.352] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[15:48:22.352] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[15:48:22.352] INFO: ----------------------------------------------------------------------
[15:48:22.352] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[15:48:22.352] INFO: ----------------------------------------------------------------------
[15:48:23.325] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[15:48:35.800] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:48:35.804] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:49:03.121] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1229053 events.
[15:49:09.247] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1229053, pixels seen in all events: 5503235
[15:49:09.345] INFO: Resuming triggers.
[15:49:21.818] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[15:49:21.855] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:49:49.117] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1228815 events.
[15:49:55.195] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1228815, pixels seen in all events: 5505543
[15:49:55.359] INFO: Resuming triggers.
[15:50:07.836] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[15:50:07.873] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:50:35.072] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1229223 events.
[15:50:41.146] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1229223, pixels seen in all events: 5500891
[15:50:41.393] INFO: Resuming triggers.
[15:50:53.864] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[15:50:53.903] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:51:21.226] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1228594 events.
[15:51:27.312] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1228594, pixels seen in all events: 5508388
[15:51:27.477] INFO: Resuming triggers.
[15:51:37.658] INFO: data taking finished, elapsed time: 60 seconds.
[15:51:37.854] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:52:00.071] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1002918 events.
[15:52:05.055] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1002918, pixels seen in all events: 4495797
[15:52:05.142] INFO: PixTest:: pg_setup set to default.
[15:52:05.237] INFO: PixTestXray::doPhRun() done
[15:52:13.096] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:52:13.096] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_02ma_C13_V0 -> hMap_02ma_mod
[15:52:15.224] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:52:15.225] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[15:52:15.225] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[15:52:15.226] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[15:52:15.226] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:52:15.226] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:52:15.226] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:52:15.226] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:52:15.226] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:52:15.226] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:52:15.226] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:52:15.226] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:52:15.226] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:52:15.226] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:52:15.226] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:52:15.226] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:52:15.226] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:52:15.226] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:52:15.227] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:52:15.227] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:52:15.227] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:52:15.227] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:52:15.513] QUIET: Connection to board 58 closed.
[15:52:15.592] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[15:52:22.134] INFO: *** Welcome to pxar ***
[15:52:22.134] INFO: *** Today: 2016/06/07
[15:52:22.161] INFO: *** Version: v1.9.0-814-g7497
[15:52:22.161] INFO: readRocDacs: data/mi212/dacParameters35_C0.dat .. data/mi212/dacParameters35_C15.dat
[15:52:22.162] INFO: readTbmDacs: data/mi212/tbmParameters_C0a.dat .. data/mi212/tbmParameters_C0b.dat
[15:52:22.162] INFO: readMaskFile: data/mi212/defaultMaskFile.dat
[15:52:22.162] INFO: readTrimFile: data/mi212/trimParameters35_C0.dat .. data/mi212/trimParameters35_C15.dat
[15:52:22.313] INFO: clk: 4
[15:52:22.313] INFO: ctr: 4
[15:52:22.313] INFO: sda: 19
[15:52:22.313] INFO: tin: 9
[15:52:22.313] INFO: level: 15
[15:52:22.313] INFO: triggerdelay: 0
[15:52:22.313] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:52:22.313] INFO: Log level: DEBUG
[15:52:22.325] QUIET: Connection to board DTB_WRPRHI opened.
[15:52:22.329] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:52:22.331] INFO: RPC call hashes of host and DTB match: 398089610
[15:52:23.934] INFO: DUT info:
[15:52:23.935] INFO: The DUT currently contains the following objects:
[15:52:23.935] INFO: 2 TBM Cores tbm08c (2 ON)
[15:52:23.935] INFO: TBM Core alpha (0): 7 registers set
[15:52:23.935] INFO: TBM Core beta (1): 7 registers set
[15:52:23.935] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:52:23.935] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:23.935] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:52:23.935] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:52:23.936] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:52:23.937] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:52:23.938] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:52:23.939] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:52:23.939] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:52:23.939] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:52:23.941] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31416320
[15:52:23.941] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2bd3bd0
[15:52:23.941] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2975e60
[15:52:23.941] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f91fdd94010
[15:52:23.941] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f9203fff510
[15:52:23.941] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31424512 fPxarMemory = 0x7f91fdd94010
[15:52:23.942] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[15:52:23.943] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[15:52:23.943] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:52:24.441] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2fbe7e0
[15:52:24.509] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:52:24.509] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:52:24.509] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:52:24.515] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:52:24.515] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:52:24.515] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:52:24.525] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:52:24.525] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:52:24.543] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:52:24.544] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:52:24.552] INFO: PixTestCmd::init()
[15:52:24.561] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:52:24.562] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:52:24.562] INFO: readGainPedestalParameters data/mi212/phCalibrationFitErr35_C0.dat .. data/mi212/phCalibrationFitErr35_C15.dat
[15:52:24.807] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:52:24.807] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:52:24.820] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:52:24.827] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:52:24.827] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:52:24.827] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:52:24.852] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:52:24.852] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:52:24.852] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:52:24.852] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:52:24.852] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:52:24.859] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:52:24.859] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:52:24.881] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:52:24.882] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:52:24.882] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:52:24.882] INFO: readReadbackCal: data/mi212/readbackCal_C0.dat .. data/mi212/readbackCal_C15.dat
[15:52:24.891] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:52:24.899] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:52:24.899] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:52:24.899] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:52:24.899] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:52:24.917] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:52:24.917] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:52:31.387] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[15:52:34.043] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[15:52:35.592] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[15:52:35.592] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[15:52:35.592] INFO: ----------------------------------------------------------------------
[15:52:35.592] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[15:52:35.592] INFO: ----------------------------------------------------------------------
[15:52:36.566] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[15:52:44.348] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:52:44.352] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:53:09.764] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 766829 events.
[15:53:21.650] DEBUG: <PixTestXray.cc/processData:L823> # events read: 766829, pixels seen in all events: 11050917
[15:53:21.905] INFO: Resuming triggers.
[15:53:29.695] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:53:29.729] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:53:55.133] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 767348 events.
[15:54:06.962] DEBUG: <PixTestXray.cc/processData:L823> # events read: 767348, pixels seen in all events: 11043159
[15:54:07.148] INFO: Resuming triggers.
[15:54:14.943] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:54:14.979] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:54:40.365] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 767876 events.
[15:54:52.198] DEBUG: <PixTestXray.cc/processData:L823> # events read: 767876, pixels seen in all events: 11037464
[15:54:52.334] INFO: Resuming triggers.
[15:55:00.133] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[15:55:00.201] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:55:25.589] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 768306 events.
[15:55:36.599] DEBUG: <PixTestXray.cc/processData:L823> # events read: 768306, pixels seen in all events: 11032807
[15:55:36.769] INFO: Resuming triggers.
[15:55:44.567] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[15:55:44.602] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:56:09.952] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 768160 events.
[15:56:20.950] DEBUG: <PixTestXray.cc/processData:L823> # events read: 768160, pixels seen in all events: 11034050
[15:56:21.114] INFO: Resuming triggers.
[15:56:28.915] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:56:28.954] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:56:54.328] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 768528 events.
[15:57:05.336] DEBUG: <PixTestXray.cc/processData:L823> # events read: 768528, pixels seen in all events: 11029147
[15:57:05.507] INFO: Resuming triggers.
[15:57:13.310] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[15:57:13.349] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:57:38.688] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 768631 events.
[15:57:49.641] DEBUG: <PixTestXray.cc/processData:L823> # events read: 768631, pixels seen in all events: 11028303
[15:57:49.746] INFO: Resuming triggers.
[15:57:55.250] INFO: data taking finished, elapsed time: 60 seconds.
[15:57:55.446] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:58:13.403] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 542240 events.
[15:58:21.064] DEBUG: <PixTestXray.cc/processData:L823> # events read: 542240, pixels seen in all events: 7780189
[15:58:21.188] INFO: PixTest:: pg_setup set to default.
[15:58:21.231] INFO: PixTestXray::doPhRun() done
[15:58:30.024] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:58:30.024] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hMap_06ma_C11_V0 -> hMap_06ma_mod
[15:58:31.712] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:58:31.713] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[15:58:31.714] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[15:58:31.714] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[15:58:31.714] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:58:31.714] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:58:31.714] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:58:31.714] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:58:31.714] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:58:31.714] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:58:31.714] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:58:31.714] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:58:31.715] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:58:31.715] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:58:31.715] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:58:31.715] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:58:31.715] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:58:31.715] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:58:31.715] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:58:31.715] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:58:31.716] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:58:31.716] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:58:32.033] QUIET: Connection to board 58 closed.
[15:58:32.113] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[15:43:08.895] INFO: *** Welcome to pxar ***
[15:43:08.895] INFO: *** Today: 2016/06/07
[15:43:08.920] INFO: *** Version: v1.9.0-814-g7497
[15:43:08.921] INFO: readRocDacs: data/mi212/dacParameters35_C0.dat .. data/mi212/dacParameters35_C15.dat
[15:43:08.922] INFO: readTbmDacs: data/mi212/tbmParameters_C0a.dat .. data/mi212/tbmParameters_C0b.dat
[15:43:08.922] INFO: readMaskFile: data/mi212/defaultMaskFile.dat
[15:43:08.922] INFO: readTrimFile: data/mi212/trimParameters35_C0.dat .. data/mi212/trimParameters35_C15.dat
[15:43:09.072] INFO: clk: 4
[15:43:09.072] INFO: ctr: 4
[15:43:09.072] INFO: sda: 19
[15:43:09.072] INFO: tin: 9
[15:43:09.072] INFO: level: 15
[15:43:09.072] INFO: triggerdelay: 0
[15:43:09.072] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:43:09.072] INFO: Log level: DEBUG
[15:43:09.083] QUIET: Connection to board DTB_WRPRHI opened.
[15:43:09.086] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:43:09.089] INFO: RPC call hashes of host and DTB match: 398089610
[15:43:10.693] INFO: DUT info:
[15:43:10.693] INFO: The DUT currently contains the following objects:
[15:43:10.693] INFO: 2 TBM Cores tbm08c (2 ON)
[15:43:10.693] INFO: TBM Core alpha (0): 7 registers set
[15:43:10.693] INFO: TBM Core beta (1): 7 registers set
[15:43:10.693] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:43:10.693] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.693] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:43:10.694] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:43:10.695] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:43:10.696] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:43:10.697] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:43:10.699] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34840576
[15:43:10.699] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xe9bbd0
[15:43:10.699] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xc3de60
[15:43:10.699] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fe9f9393010
[15:43:10.699] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fe9ff5fe510
[15:43:10.699] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34848768 fPxarMemory = 0x7fe9f9393010
[15:43:10.700] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[15:43:10.701] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[15:43:10.701] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:43:11.201] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x12868c0
[15:43:11.270] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:43:11.270] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:43:11.270] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:43:11.276] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:43:11.276] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:43:11.276] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:43:11.287] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:43:11.287] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:43:11.303] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:43:11.303] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:43:11.318] INFO: PixTestCmd::init()
[15:43:11.326] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:43:11.326] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:43:11.326] INFO: readGainPedestalParameters data/mi212/phCalibrationFitErr35_C0.dat .. data/mi212/phCalibrationFitErr35_C15.dat
[15:43:11.569] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:43:11.569] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:43:11.583] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:43:11.589] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:43:11.589] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:43:11.589] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:43:11.613] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:43:11.613] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:43:11.613] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:43:11.613] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:43:11.613] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:43:11.620] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:43:11.620] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:43:11.643] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:43:11.643] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:43:11.643] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:43:11.643] INFO: readReadbackCal: data/mi212/readbackCal_C0.dat .. data/mi212/readbackCal_C15.dat
[15:43:11.655] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:43:11.667] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:43:11.667] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:43:11.667] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:43:11.667] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:43:11.680] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:43:11.680] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:43:18.211] INFO: ######################################################################
[15:43:18.211] INFO: PixTestAlive::doTest()
[15:43:18.211] INFO: ######################################################################
[15:43:18.214] INFO: ----------------------------------------------------------------------
[15:43:18.214] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:43:18.214] INFO: ----------------------------------------------------------------------
[15:43:18.217] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:43:18.595] INFO: Expecting 41600 events.
[15:43:22.987] INFO: 41600 events read in total (3673ms).
[15:43:23.132] INFO: Test took 4915ms.
[15:43:23.145] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:23.145] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[15:43:23.145] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:43:23.433] INFO: PixTestAlive::aliveTest() done
[15:43:23.433] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 1 0
[15:43:23.433] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 21 15 30 30 50 42 39 41 39 43 32 55 44 32 18 15
[15:43:23.461] INFO: ----------------------------------------------------------------------
[15:43:23.461] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:43:23.461] INFO: ----------------------------------------------------------------------
[15:43:23.464] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:43:23.842] INFO: Expecting 41600 events.
[15:43:26.962] INFO: 41600 events read in total (2405ms).
[15:43:26.963] INFO: Test took 3499ms.
[15:43:26.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:26.963] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:43:26.963] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:43:26.963] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:43:27.386] INFO: PixTestAlive::maskTest() done
[15:43:27.386] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:43:27.408] INFO: ----------------------------------------------------------------------
[15:43:27.408] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:43:27.408] INFO: ----------------------------------------------------------------------
[15:43:27.411] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:43:27.790] INFO: Expecting 41600 events.
[15:43:32.195] INFO: 41600 events read in total (3690ms).
[15:43:32.197] INFO: Test took 4786ms.
[15:43:32.208] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:32.208] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[15:43:32.208] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:43:32.579] INFO: PixTestAlive::addressDecodingTest() done
[15:43:32.579] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:43:32.579] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:43:35.488] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:43:35.488] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:43:35.488] INFO: ----------------------------------------------------------------------
[15:43:35.488] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:43:35.488] INFO: ----------------------------------------------------------------------
[15:43:35.627] INFO: Expecting 768 events.
[15:43:36.761] INFO: 768 events read in total (418ms).
[15:43:36.762] INFO: Test took 1268ms.
[15:43:36.767] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:43:37.565] INFO: Expecting 41600 events.
[15:43:40.908] INFO: 41600 events read in total (2817ms).
[15:43:40.912] INFO: Test took 4145ms.
[15:43:40.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:40.997] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 486889
[15:43:40.997] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:43:40.997] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:43:41.036] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:43:41.701] INFO: Expecting 41600 events.
[15:43:45.166] INFO: 41600 events read in total (2938ms).
[15:43:45.170] INFO: Test took 4134ms.
[15:43:45.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:45.252] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 495651
[15:43:45.252] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:43:45.252] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:43:45.293] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:43:45.958] INFO: Expecting 41600 events.
[15:43:49.482] INFO: 41600 events read in total (2997ms).
[15:43:49.486] INFO: Test took 4193ms.
[15:43:49.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:49.568] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 498420
[15:43:49.568] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:43:49.568] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:43:49.609] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:43:50.274] INFO: Expecting 41600 events.
[15:43:53.799] INFO: 41600 events read in total (2998ms).
[15:43:53.803] INFO: Test took 4194ms.
[15:43:53.884] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:53.884] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499797
[15:43:53.884] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:43:53.885] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:43:53.925] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:43:54.590] INFO: Expecting 41600 events.
[15:43:58.122] INFO: 41600 events read in total (3005ms).
[15:43:58.126] INFO: Test took 4200ms.
[15:43:58.208] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:58.208] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 500445
[15:43:58.208] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:43:58.209] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:43:58.249] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:43:58.914] INFO: Expecting 41600 events.
[15:44:02.429] INFO: 41600 events read in total (2988ms).
[15:44:02.433] INFO: Test took 4184ms.
[15:44:02.515] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:02.515] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499506
[15:44:02.515] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:44:02.515] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:02.556] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:03.221] INFO: Expecting 41600 events.
[15:44:06.753] INFO: 41600 events read in total (3005ms).
[15:44:06.757] INFO: Test took 4201ms.
[15:44:06.840] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:06.840] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499538
[15:44:06.840] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:44:06.841] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:06.881] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:07.547] INFO: Expecting 41600 events.
[15:44:11.100] INFO: 41600 events read in total (3027ms).
[15:44:11.103] INFO: Test took 4222ms.
[15:44:11.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:11.186] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499709
[15:44:11.186] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:44:11.186] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:11.227] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:11.892] INFO: Expecting 41600 events.
[15:44:15.437] INFO: 41600 events read in total (3018ms).
[15:44:15.441] INFO: Test took 4214ms.
[15:44:15.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:15.522] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499773
[15:44:15.523] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:44:15.523] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:15.564] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:16.228] INFO: Expecting 41600 events.
[15:44:19.762] INFO: 41600 events read in total (3007ms).
[15:44:19.766] INFO: Test took 4202ms.
[15:44:19.847] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:19.847] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499316
[15:44:19.847] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:44:19.848] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:19.889] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:20.554] INFO: Expecting 41600 events.
[15:44:24.100] INFO: 41600 events read in total (3019ms).
[15:44:24.104] INFO: Test took 4215ms.
[15:44:24.187] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:24.187] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 500302
[15:44:24.187] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:44:24.187] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:24.228] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:24.893] INFO: Expecting 41600 events.
[15:44:28.441] INFO: 41600 events read in total (3021ms).
[15:44:28.445] INFO: Test took 4217ms.
[15:44:28.527] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:28.527] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 500148
[15:44:28.527] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:44:28.527] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:28.568] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:29.233] INFO: Expecting 41600 events.
[15:44:32.770] INFO: 41600 events read in total (3010ms).
[15:44:32.774] INFO: Test took 4206ms.
[15:44:32.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:32.856] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 498914
[15:44:32.856] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:44:32.856] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:32.897] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:33.562] INFO: Expecting 41600 events.
[15:44:37.109] INFO: 41600 events read in total (3020ms).
[15:44:37.112] INFO: Test took 4215ms.
[15:44:37.194] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:37.194] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 500574
[15:44:37.194] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:44:37.194] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:37.236] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:37.901] INFO: Expecting 41600 events.
[15:44:41.441] INFO: 41600 events read in total (3014ms).
[15:44:41.445] INFO: Test took 4209ms.
[15:44:41.527] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:41.527] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 499457
[15:44:41.527] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:44:41.528] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:41.568] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:42.233] INFO: Expecting 41600 events.
[15:44:45.770] INFO: 41600 events read in total (3010ms).
[15:44:45.774] INFO: Test took 4206ms.
[15:44:45.856] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:45.856] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 498741
[15:44:45.856] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:44:45.857] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:45.898] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:46.563] INFO: Expecting 41600 events.
[15:44:50.099] INFO: 41600 events read in total (3010ms).
[15:44:50.102] INFO: Test took 4204ms.
[15:44:50.184] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:50.184] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 500900
[15:44:50.184] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:44:50.185] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:50.226] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:50.891] INFO: Expecting 41600 events.
[15:44:54.436] INFO: 41600 events read in total (3018ms).
[15:44:54.440] INFO: Test took 4214ms.
[15:44:54.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:54.522] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 498374
[15:44:54.522] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:44:54.522] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:54.563] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:55.228] INFO: Expecting 41600 events.
[15:44:58.751] INFO: 41600 events read in total (2996ms).
[15:44:58.755] INFO: Test took 4192ms.
[15:44:58.837] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:44:58.837] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 498090
[15:44:58.837] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:44:58.837] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:44:58.878] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:44:59.543] INFO: Expecting 41600 events.
[15:45:02.978] INFO: 41600 events read in total (2909ms).
[15:45:02.982] INFO: Test took 4104ms.
[15:45:03.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:03.066] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 496050
[15:45:03.066] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:45:03.066] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:45:03.328] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 136 eff = 0.999639
[15:45:03.329] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 127 eff = 0.999712
[15:45:03.329] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 164 eff = 0.999399
[15:45:03.329] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 169 eff = 0.998822
[15:45:03.329] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 156 eff = 0.998942
[15:45:03.330] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 164 eff = 0.998918
[15:45:03.330] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 162 eff = 0.998822
[15:45:03.330] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 156 eff = 0.998846
[15:45:03.330] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 140 eff = 0.99887
[15:45:03.331] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 157 eff = 0.99863
[15:45:03.331] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 157 eff = 0.998726
[15:45:03.331] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 169 eff = 0.99875
[15:45:03.331] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 157 eff = 0.99887
[15:45:03.332] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 139 eff = 0.999423
[15:45:03.332] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 152 eff = 0.999519
[15:45:03.332] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 165 eff = 0.999615
[15:45:07.680] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:45:07.680] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:45:07.680] INFO: ----------------------------------------------------------------------
[15:45:07.680] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:45:07.680] INFO: ----------------------------------------------------------------------
[15:45:07.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:45:07.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:45:07.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:45:07.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:45:07.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:45:07.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:45:07.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:45:07.681] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:45:07.681] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:45:07.681] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:45:07.681] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:45:07.681] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:45:07.699] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:45:08.367] INFO: Expecting 208000 events.
[15:45:22.142] INFO: 208000 events read in total (13248ms).
[15:45:22.154] INFO: Test took 14456ms.
[15:45:22.545] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:45:22.545] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2217152
[15:45:22.545] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:45:22.545] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:45:22.922] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 1 0
[15:45:22.922] INFO: number of red-efficiency pixels: 119 86 129 238 236 237 273 210 237 314 233 231 257 199 91 88
[15:45:22.922] INFO: number of X-ray hits detected: 84782 63376 103017 155274 163270 166542 166167 156541 170927 175440 170342 161219 160941 108262 65994 78500
[15:45:22.922] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:45:22.922] INFO: number of Vcal hits detected: 207880 207912 207870 207756 207752 207754 207717 207768 207757 207672 207761 207714 207725 207789 207859 207910
[15:45:22.922] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:45:22.922] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 100.0
[15:45:22.922] INFO: X-ray hit rate [MHz/cm2]: 24.9 18.6 30.2 45.5 47.9 48.8 48.7 45.9 50.1 51.4 49.9 47.3 47.2 31.7 19.3 23.0
[15:45:22.922] INFO: PixTestHighRate::doXPixelAlive() done
[15:45:22.966] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:45:22.967] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:45:22.967] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:45:22.967] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:45:22.967] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:45:22.967] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:45:22.967] INFO: PixTest:: pg_setup set to default.
[15:45:37.336] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:45:37.336] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C8_V0 -> highRate_xraymap_mod
[15:45:39.088] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:45:39.089] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[15:45:39.089] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[15:45:39.089] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[15:45:39.089] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:45:39.090] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:45:39.090] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:45:39.090] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:45:39.090] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:45:39.090] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:45:39.090] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:45:39.090] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:45:39.090] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:45:39.090] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:45:39.179] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:45:39.180] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:45:39.189] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:45:39.189] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:45:39.189] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:45:39.189] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:45:39.189] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:45:39.189] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:45:39.193] QUIET: Connection to board 58 closed.
[15:45:39.273] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[15:40:19.963] INFO: *** Welcome to pxar ***
[15:40:19.963] INFO: *** Today: 2016/06/07
[15:40:19.987] INFO: *** Version: v1.9.0-814-g7497
[15:40:19.987] INFO: readRocDacs: data/mi212/dacParameters35_C0.dat .. data/mi212/dacParameters35_C15.dat
[15:40:19.988] INFO: readTbmDacs: data/mi212/tbmParameters_C0a.dat .. data/mi212/tbmParameters_C0b.dat
[15:40:19.988] INFO: readMaskFile: data/mi212/defaultMaskFile.dat
[15:40:19.989] INFO: readTrimFile: data/mi212/trimParameters35_C0.dat .. data/mi212/trimParameters35_C15.dat
[15:40:20.142] INFO: clk: 4
[15:40:20.142] INFO: ctr: 4
[15:40:20.142] INFO: sda: 19
[15:40:20.142] INFO: tin: 9
[15:40:20.142] INFO: level: 15
[15:40:20.143] INFO: triggerdelay: 0
[15:40:20.143] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:40:20.143] INFO: Log level: DEBUG
[15:40:20.154] QUIET: Connection to board DTB_WRPRHI opened.
[15:40:20.157] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:40:20.160] INFO: RPC call hashes of host and DTB match: 398089610
[15:40:21.763] INFO: DUT info:
[15:40:21.763] INFO: The DUT currently contains the following objects:
[15:40:21.763] INFO: 2 TBM Cores tbm08c (2 ON)
[15:40:21.763] INFO: TBM Core alpha (0): 7 registers set
[15:40:21.763] INFO: TBM Core beta (1): 7 registers set
[15:40:21.763] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:40:21.763] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.763] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.763] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.763] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.763] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.763] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.763] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.763] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.763] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.763] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.763] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.764] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.764] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.764] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.764] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.764] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:40:21.764] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:40:21.765] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:40:21.766] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:40:21.767] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:40:21.769] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31440896
[15:40:21.769] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x2784bd0
[15:40:21.769] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2526e60
[15:40:21.769] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f5f05d94010
[15:40:21.769] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f5f0bfff510
[15:40:21.769] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31449088 fPxarMemory = 0x7f5f05d94010
[15:40:21.771] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[15:40:21.772] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[15:40:21.772] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[15:40:22.268] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2b6f850
[15:40:22.329] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:40:22.329] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:40:22.329] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:40:22.335] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:40:22.335] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:40:22.335] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:40:22.344] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:40:22.344] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:40:22.355] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:40:22.355] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:40:22.372] INFO: PixTestCmd::init()
[15:40:22.381] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:40:22.381] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:40:22.381] INFO: readGainPedestalParameters data/mi212/phCalibrationFitErr35_C0.dat .. data/mi212/phCalibrationFitErr35_C15.dat
[15:40:22.626] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:40:22.626] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:40:22.640] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:40:22.646] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:40:22.646] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:40:22.646] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:40:22.670] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:40:22.670] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:40:22.670] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:40:22.670] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:40:22.670] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:40:22.676] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:40:22.676] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:40:22.691] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:40:22.692] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:40:22.692] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:40:22.692] INFO: readReadbackCal: data/mi212/readbackCal_C0.dat .. data/mi212/readbackCal_C15.dat
[15:40:22.701] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:40:22.709] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:40:22.709] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:40:22.709] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:40:22.709] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:40:22.727] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:40:22.727] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:40:28.323] INFO: ######################################################################
[15:40:28.323] INFO: PixTestAlive::doTest()
[15:40:28.323] INFO: ######################################################################
[15:40:28.326] INFO: ----------------------------------------------------------------------
[15:40:28.326] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:40:28.326] INFO: ----------------------------------------------------------------------
[15:40:28.329] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:40:28.707] INFO: Expecting 41600 events.
[15:40:33.110] INFO: 41600 events read in total (3685ms).
[15:40:33.257] INFO: Test took 4928ms.
[15:40:33.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:33.268] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[15:40:33.268] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:40:33.538] INFO: PixTestAlive::aliveTest() done
[15:40:33.538] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 1 0
[15:40:33.538] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 27 21 34 59 55 69 74 59 68 59 65 56 66 36 24 32
[15:40:33.564] INFO: ----------------------------------------------------------------------
[15:40:33.564] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:40:33.564] INFO: ----------------------------------------------------------------------
[15:40:33.566] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:40:33.944] INFO: Expecting 41600 events.
[15:40:37.069] INFO: 41600 events read in total (2410ms).
[15:40:37.070] INFO: Test took 3504ms.
[15:40:37.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:37.070] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:40:37.070] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:40:37.071] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:40:37.492] INFO: PixTestAlive::maskTest() done
[15:40:37.492] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:40:37.514] INFO: ----------------------------------------------------------------------
[15:40:37.515] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:40:37.515] INFO: ----------------------------------------------------------------------
[15:40:37.517] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:40:37.894] INFO: Expecting 41600 events.
[15:40:42.268] INFO: 41600 events read in total (3659ms).
[15:40:42.270] INFO: Test took 4753ms.
[15:40:42.280] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:42.280] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[15:40:42.281] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:40:42.652] INFO: PixTestAlive::addressDecodingTest() done
[15:40:42.652] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:40:42.652] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:40:45.600] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:40:45.600] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:40:45.600] INFO: ----------------------------------------------------------------------
[15:40:45.600] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:40:45.600] INFO: ----------------------------------------------------------------------
[15:40:45.739] INFO: Expecting 768 events.
[15:40:46.873] INFO: 768 events read in total (419ms).
[15:40:46.873] INFO: Test took 1267ms.
[15:40:46.879] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:40:47.677] INFO: Expecting 41600 events.
[15:40:51.329] INFO: 41600 events read in total (3126ms).
[15:40:51.334] INFO: Test took 4455ms.
[15:40:51.457] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:51.457] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 707669
[15:40:51.457] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:40:51.458] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:40:51.512] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:40:52.177] INFO: Expecting 41600 events.
[15:40:55.950] INFO: 41600 events read in total (3246ms).
[15:40:55.954] INFO: Test took 4442ms.
[15:40:56.074] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:56.074] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 716868
[15:40:56.074] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:40:56.075] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:40:56.133] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:40:56.799] INFO: Expecting 41600 events.
[15:41:00.606] INFO: 41600 events read in total (3280ms).
[15:41:00.610] INFO: Test took 4477ms.
[15:41:00.730] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:00.730] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718525
[15:41:00.730] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:41:00.730] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:00.786] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:01.451] INFO: Expecting 41600 events.
[15:41:05.267] INFO: 41600 events read in total (3289ms).
[15:41:05.272] INFO: Test took 4486ms.
[15:41:05.393] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:05.393] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718538
[15:41:05.393] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:41:05.394] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:05.450] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:06.115] INFO: Expecting 41600 events.
[15:41:09.924] INFO: 41600 events read in total (3282ms).
[15:41:09.929] INFO: Test took 4479ms.
[15:41:10.054] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:10.054] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 716756
[15:41:10.054] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:41:10.055] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:10.111] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:10.776] INFO: Expecting 41600 events.
[15:41:14.601] INFO: 41600 events read in total (3298ms).
[15:41:14.605] INFO: Test took 4494ms.
[15:41:14.724] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:14.724] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718639
[15:41:14.725] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:41:14.725] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:14.782] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:15.447] INFO: Expecting 41600 events.
[15:41:19.282] INFO: 41600 events read in total (3309ms).
[15:41:19.287] INFO: Test took 4505ms.
[15:41:19.408] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:19.408] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718514
[15:41:19.408] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:41:19.408] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:19.465] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:20.130] INFO: Expecting 41600 events.
[15:41:23.964] INFO: 41600 events read in total (3308ms).
[15:41:23.968] INFO: Test took 4503ms.
[15:41:24.090] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:24.090] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 717581
[15:41:24.090] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:41:24.090] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:24.147] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:24.812] INFO: Expecting 41600 events.
[15:41:28.633] INFO: 41600 events read in total (3296ms).
[15:41:28.638] INFO: Test took 4491ms.
[15:41:28.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:28.758] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 717795
[15:41:28.758] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:41:28.758] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:28.815] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:29.480] INFO: Expecting 41600 events.
[15:41:33.292] INFO: 41600 events read in total (3286ms).
[15:41:33.297] INFO: Test took 4482ms.
[15:41:33.419] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:33.419] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720357
[15:41:33.419] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:41:33.420] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:33.476] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:34.141] INFO: Expecting 41600 events.
[15:41:37.969] INFO: 41600 events read in total (3301ms).
[15:41:37.974] INFO: Test took 4498ms.
[15:41:38.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:38.094] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 719847
[15:41:38.094] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:41:38.094] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:38.151] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:38.816] INFO: Expecting 41600 events.
[15:41:42.632] INFO: 41600 events read in total (3289ms).
[15:41:42.637] INFO: Test took 4486ms.
[15:41:42.757] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:42.757] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718865
[15:41:42.757] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:41:42.757] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:42.815] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:43.482] INFO: Expecting 41600 events.
[15:41:47.280] INFO: 41600 events read in total (3272ms).
[15:41:47.284] INFO: Test took 4469ms.
[15:41:47.404] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:47.404] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718748
[15:41:47.404] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:41:47.404] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:47.461] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:48.126] INFO: Expecting 41600 events.
[15:41:51.945] INFO: 41600 events read in total (3293ms).
[15:41:51.950] INFO: Test took 4489ms.
[15:41:52.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:52.069] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 717544
[15:41:52.069] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:41:52.069] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:52.127] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:52.792] INFO: Expecting 41600 events.
[15:41:56.612] INFO: 41600 events read in total (3293ms).
[15:41:56.616] INFO: Test took 4489ms.
[15:41:56.736] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:41:56.737] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718228
[15:41:56.737] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:41:56.737] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:41:56.793] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:41:57.458] INFO: Expecting 41600 events.
[15:42:01.264] INFO: 41600 events read in total (3279ms).
[15:42:01.269] INFO: Test took 4476ms.
[15:42:01.390] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:01.390] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718866
[15:42:01.390] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:42:01.390] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:01.447] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:02.111] INFO: Expecting 41600 events.
[15:42:05.944] INFO: 41600 events read in total (3306ms).
[15:42:05.949] INFO: Test took 4502ms.
[15:42:06.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:06.069] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 718157
[15:42:06.069] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:42:06.070] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:06.127] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:06.792] INFO: Expecting 41600 events.
[15:42:10.633] INFO: 41600 events read in total (3315ms).
[15:42:10.638] INFO: Test took 4511ms.
[15:42:10.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:10.758] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 720418
[15:42:10.758] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:42:10.759] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:10.816] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:11.480] INFO: Expecting 41600 events.
[15:42:15.293] INFO: 41600 events read in total (3286ms).
[15:42:15.297] INFO: Test took 4481ms.
[15:42:15.418] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:15.418] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 716974
[15:42:15.418] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:42:15.419] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:15.476] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:16.141] INFO: Expecting 41600 events.
[15:42:19.842] INFO: 41600 events read in total (3174ms).
[15:42:19.847] INFO: Test took 4371ms.
[15:42:19.965] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:19.965] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 714377
[15:42:19.965] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:42:19.966] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:20.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 156 eff = 0.999303
[15:42:20.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 149 eff = 0.999663
[15:42:20.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 169 eff = 0.998942
[15:42:20.244] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 171 eff = 0.998029
[15:42:20.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 152 eff = 0.997596
[15:42:20.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 163 eff = 0.997548
[15:42:20.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 159 eff = 0.997548
[15:42:20.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 152 eff = 0.997788
[15:42:20.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 139 eff = 0.997163
[15:42:20.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 172 eff = 0.997452
[15:42:20.245] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 160 eff = 0.997813
[15:42:20.246] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 168 eff = 0.997212
[15:42:20.246] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 157 eff = 0.997692
[15:42:20.246] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 157 eff = 0.998726
[15:42:20.246] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 161 eff = 0.999231
[15:42:20.246] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 156 eff = 0.999375
[15:42:30.248] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:42:30.248] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:42:30.248] INFO: ----------------------------------------------------------------------
[15:42:30.248] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:42:30.248] INFO: ----------------------------------------------------------------------
[15:42:30.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:42:30.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:42:30.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:42:30.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:42:30.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:42:30.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:42:30.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:42:30.248] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:42:30.249] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:42:30.249] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:42:30.249] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:42:30.249] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:42:30.266] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:42:30.933] INFO: Expecting 208000 events.
[15:42:46.372] INFO: 208000 events read in total (14913ms).
[15:42:46.389] INFO: Test took 16123ms.
[15:42:46.959] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:42:46.959] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3290805
[15:42:46.959] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:42:46.959] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:42:47.403] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 1 0
[15:42:47.404] INFO: number of red-efficiency pixels: 155 112 282 431 533 479 506 484 519 518 482 428 514 294 132 179
[15:42:47.404] INFO: number of X-ray hits detected: 127499 94700 153920 232486 244701 248796 250335 235670 254781 262175 254895 242061 241396 162938 98588 119306
[15:42:47.404] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:42:47.404] INFO: number of Vcal hits detected: 207842 207882 207691 207536 207402 207462 207452 207468 207437 207423 207476 207483 207438 207689 207817 207816
[15:42:47.404] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.8 99.7 99.7 99.8 99.8 99.8 99.9 99.9 99.9
[15:42:47.404] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.7 99.7 99.8 99.7 99.9 99.9 99.9
[15:42:47.404] INFO: X-ray hit rate [MHz/cm2]: 37.4 27.8 45.1 68.1 71.7 72.9 73.4 69.1 74.7 76.8 74.7 70.9 70.8 47.8 28.9 35.0
[15:42:47.404] INFO: PixTestHighRate::doXPixelAlive() done
[15:42:47.448] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:42:47.448] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:42:47.448] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:42:47.448] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:42:47.448] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:42:47.448] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:42:47.448] INFO: PixTest:: pg_setup set to default.
[15:43:00.688] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:43:00.688] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C9_V0 -> highRate_xraymap_mod
[15:43:02.304] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:43:02.304] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[15:43:02.305] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[15:43:02.305] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:43:02.305] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:43:02.305] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:43:02.305] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:43:02.305] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:43:02.306] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:43:02.306] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:43:02.306] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:43:02.306] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:43:02.306] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:43:02.306] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:43:02.397] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:43:02.398] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:43:02.407] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:43:02.407] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:43:02.407] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:43:02.407] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:43:02.407] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:43:02.407] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:43:02.411] QUIET: Connection to board 58 closed.
[15:43:02.490] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[15:37:03.341] INFO: *** Welcome to pxar ***
[15:37:03.341] INFO: *** Today: 2016/06/07
[15:37:03.365] INFO: *** Version: v1.9.0-814-g7497
[15:37:03.365] INFO: readRocDacs: data/mi212/dacParameters35_C0.dat .. data/mi212/dacParameters35_C15.dat
[15:37:03.366] INFO: readTbmDacs: data/mi212/tbmParameters_C0a.dat .. data/mi212/tbmParameters_C0b.dat
[15:37:03.366] INFO: readMaskFile: data/mi212/defaultMaskFile.dat
[15:37:03.366] INFO: readTrimFile: data/mi212/trimParameters35_C0.dat .. data/mi212/trimParameters35_C15.dat
[15:37:03.515] INFO: clk: 4
[15:37:03.515] INFO: ctr: 4
[15:37:03.515] INFO: sda: 19
[15:37:03.515] INFO: tin: 9
[15:37:03.515] INFO: level: 15
[15:37:03.515] INFO: triggerdelay: 0
[15:37:03.515] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:37:03.515] INFO: Log level: DEBUG
[15:37:03.527] QUIET: Connection to board DTB_WRPRHI opened.
[15:37:03.530] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:37:03.533] INFO: RPC call hashes of host and DTB match: 398089610
[15:37:05.136] INFO: DUT info:
[15:37:05.136] INFO: The DUT currently contains the following objects:
[15:37:05.136] INFO: 2 TBM Cores tbm08c (2 ON)
[15:37:05.136] INFO: TBM Core alpha (0): 7 registers set
[15:37:05.136] INFO: TBM Core beta (1): 7 registers set
[15:37:05.136] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:37:05.136] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.136] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:37:05.137] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:37:05.138] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:37:05.139] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:37:05.140] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:37:05.140] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:37:05.140] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:37:05.140] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:37:05.140] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:37:05.140] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:37:05.140] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:37:05.140] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:37:05.140] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:37:05.140] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:37:05.142] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 36937728
[15:37:05.142] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x10d7bd0
[15:37:05.142] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xe79e60
[15:37:05.142] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f7729393010
[15:37:05.142] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f772f5fe510
[15:37:05.142] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 36945920 fPxarMemory = 0x7f7729393010
[15:37:05.143] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[15:37:05.144] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[15:37:05.144] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:37:05.636] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x14c27f0
[15:37:05.696] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:37:05.696] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:37:05.696] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:37:05.702] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:37:05.702] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:37:05.702] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:37:05.716] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:37:05.717] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:37:05.741] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:37:05.741] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:37:05.750] INFO: PixTestCmd::init()
[15:37:05.754] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:37:05.754] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:37:05.754] INFO: readGainPedestalParameters data/mi212/phCalibrationFitErr35_C0.dat .. data/mi212/phCalibrationFitErr35_C15.dat
[15:37:05.999] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:37:05.999] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:37:06.012] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:37:06.018] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:37:06.018] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:37:06.018] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:37:06.041] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:37:06.041] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:37:06.041] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:37:06.041] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:37:06.041] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:37:06.047] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:37:06.047] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:37:06.062] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:37:06.062] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:37:06.062] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:37:06.062] INFO: readReadbackCal: data/mi212/readbackCal_C0.dat .. data/mi212/readbackCal_C15.dat
[15:37:06.073] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:37:06.081] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:37:06.081] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:37:06.081] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:37:06.082] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:37:06.101] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:37:06.101] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:37:09.899] INFO: ######################################################################
[15:37:09.899] INFO: PixTestAlive::doTest()
[15:37:09.899] INFO: ######################################################################
[15:37:09.902] INFO: ----------------------------------------------------------------------
[15:37:09.902] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:37:09.902] INFO: ----------------------------------------------------------------------
[15:37:09.905] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:10.284] INFO: Expecting 41600 events.
[15:37:14.686] INFO: 41600 events read in total (3684ms).
[15:37:14.833] INFO: Test took 4928ms.
[15:37:14.844] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:14.844] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[15:37:14.844] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:37:15.116] INFO: PixTestAlive::aliveTest() done
[15:37:15.116] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 1 0
[15:37:15.116] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 41 32 54 77 94 80 94 87 103 89 60 97 80 49 34 47
[15:37:15.144] INFO: ----------------------------------------------------------------------
[15:37:15.144] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:37:15.144] INFO: ----------------------------------------------------------------------
[15:37:15.146] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:15.524] INFO: Expecting 41600 events.
[15:37:18.669] INFO: 41600 events read in total (2430ms).
[15:37:18.669] INFO: Test took 3523ms.
[15:37:18.669] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:18.669] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:37:18.669] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:37:18.670] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:37:19.092] INFO: PixTestAlive::maskTest() done
[15:37:19.092] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:37:19.114] INFO: ----------------------------------------------------------------------
[15:37:19.114] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:37:19.114] INFO: ----------------------------------------------------------------------
[15:37:19.116] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:37:19.494] INFO: Expecting 41600 events.
[15:37:23.893] INFO: 41600 events read in total (3684ms).
[15:37:23.894] INFO: Test took 4778ms.
[15:37:23.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:37:23.905] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[15:37:23.905] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:37:24.271] INFO: PixTestAlive::addressDecodingTest() done
[15:37:24.271] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:37:24.271] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:37:58.784] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:37:58.784] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:37:58.784] INFO: ----------------------------------------------------------------------
[15:37:58.784] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:37:58.784] INFO: ----------------------------------------------------------------------
[15:37:58.923] INFO: Expecting 768 events.
[15:38:00.055] INFO: 768 events read in total (417ms).
[15:38:00.056] INFO: Test took 1267ms.
[15:38:00.062] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:00.859] INFO: Expecting 41600 events.
[15:38:04.772] INFO: 41600 events read in total (3386ms).
[15:38:04.777] INFO: Test took 4715ms.
[15:38:04.938] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:04.938] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 922183
[15:38:04.938] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:38:04.939] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:05.005] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:05.671] INFO: Expecting 41600 events.
[15:38:09.692] INFO: 41600 events read in total (3495ms).
[15:38:09.698] INFO: Test took 4693ms.
[15:38:09.848] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:09.848] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 931028
[15:38:09.848] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:38:09.849] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:09.918] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:10.584] INFO: Expecting 41600 events.
[15:38:14.645] INFO: 41600 events read in total (3534ms).
[15:38:14.650] INFO: Test took 4732ms.
[15:38:14.801] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:14.801] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 932931
[15:38:14.801] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:38:14.801] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:14.871] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:15.536] INFO: Expecting 41600 events.
[15:38:19.607] INFO: 41600 events read in total (3545ms).
[15:38:19.613] INFO: Test took 4742ms.
[15:38:19.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:19.762] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 932456
[15:38:19.762] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:38:19.763] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:19.834] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:20.500] INFO: Expecting 41600 events.
[15:38:24.571] INFO: 41600 events read in total (3545ms).
[15:38:24.576] INFO: Test took 4743ms.
[15:38:24.734] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:24.734] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 932710
[15:38:24.734] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:38:24.734] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:24.802] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:25.468] INFO: Expecting 41600 events.
[15:38:29.530] INFO: 41600 events read in total (3536ms).
[15:38:29.536] INFO: Test took 4734ms.
[15:38:29.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:29.688] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 931841
[15:38:29.688] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:38:29.688] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:29.757] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:30.422] INFO: Expecting 41600 events.
[15:38:34.509] INFO: 41600 events read in total (3560ms).
[15:38:34.515] INFO: Test took 4757ms.
[15:38:34.669] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:34.669] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 931837
[15:38:34.669] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:38:34.670] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:34.738] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:35.403] INFO: Expecting 41600 events.
[15:38:39.493] INFO: 41600 events read in total (3564ms).
[15:38:39.499] INFO: Test took 4761ms.
[15:38:39.652] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:39.652] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 933395
[15:38:39.652] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:38:39.653] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:39.720] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:40.385] INFO: Expecting 41600 events.
[15:38:44.475] INFO: 41600 events read in total (3563ms).
[15:38:44.481] INFO: Test took 4761ms.
[15:38:44.632] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:44.632] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 932118
[15:38:44.632] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:38:44.632] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:44.703] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:45.368] INFO: Expecting 41600 events.
[15:38:49.463] INFO: 41600 events read in total (3568ms).
[15:38:49.469] INFO: Test took 4766ms.
[15:38:49.622] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:49.623] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 935193
[15:38:49.623] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:38:49.623] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:49.691] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:50.356] INFO: Expecting 41600 events.
[15:38:54.444] INFO: 41600 events read in total (3561ms).
[15:38:54.449] INFO: Test took 4758ms.
[15:38:54.604] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:54.604] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 935242
[15:38:54.604] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:38:54.605] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:54.675] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:38:55.339] INFO: Expecting 41600 events.
[15:38:59.428] INFO: 41600 events read in total (3562ms).
[15:38:59.434] INFO: Test took 4759ms.
[15:38:59.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:38:59.585] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 933215
[15:38:59.585] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:38:59.586] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:38:59.656] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:39:00.321] INFO: Expecting 41600 events.
[15:39:04.424] INFO: 41600 events read in total (3576ms).
[15:39:04.429] INFO: Test took 4773ms.
[15:39:04.585] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:04.585] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 934662
[15:39:04.585] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:39:04.585] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:39:04.654] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:39:05.320] INFO: Expecting 41600 events.
[15:39:09.417] INFO: 41600 events read in total (3571ms).
[15:39:09.422] INFO: Test took 4768ms.
[15:39:09.575] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:09.575] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 933780
[15:39:09.575] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:39:09.576] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:39:09.644] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:39:10.308] INFO: Expecting 41600 events.
[15:39:14.404] INFO: 41600 events read in total (3569ms).
[15:39:14.409] INFO: Test took 4765ms.
[15:39:14.563] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:14.563] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 934814
[15:39:14.563] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:39:14.563] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:39:14.631] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:39:15.297] INFO: Expecting 41600 events.
[15:39:19.395] INFO: 41600 events read in total (3572ms).
[15:39:19.400] INFO: Test took 4769ms.
[15:39:19.553] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:19.553] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 934909
[15:39:19.553] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:39:19.553] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:39:19.622] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:39:20.288] INFO: Expecting 41600 events.
[15:39:24.373] INFO: 41600 events read in total (3559ms).
[15:39:24.378] INFO: Test took 4756ms.
[15:39:24.532] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:24.532] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 932417
[15:39:24.533] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:39:24.533] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:39:24.602] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:39:25.267] INFO: Expecting 41600 events.
[15:39:29.352] INFO: 41600 events read in total (3558ms).
[15:39:29.357] INFO: Test took 4755ms.
[15:39:29.510] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:29.510] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 932991
[15:39:29.510] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:39:29.510] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:39:29.580] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:39:30.245] INFO: Expecting 41600 events.
[15:39:34.303] INFO: 41600 events read in total (3531ms).
[15:39:34.309] INFO: Test took 4729ms.
[15:39:34.463] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:34.463] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 934172
[15:39:34.463] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:39:34.463] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:39:34.532] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:39:35.197] INFO: Expecting 41600 events.
[15:39:39.127] INFO: 41600 events read in total (3403ms).
[15:39:39.132] INFO: Test took 4600ms.
[15:39:39.282] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:39.282] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 926752
[15:39:39.282] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:39:39.283] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:39:39.574] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 154 eff = 0.998678
[15:39:39.574] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 149 eff = 0.999135
[15:39:39.574] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 166 eff = 0.9975
[15:39:39.575] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 173 eff = 0.995817
[15:39:39.575] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 155 eff = 0.995673
[15:39:39.575] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 161 eff = 0.995721
[15:39:39.575] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 159 eff = 0.994712
[15:39:39.576] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 155 eff = 0.995745
[15:39:39.576] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 140 eff = 0.995817
[15:39:39.576] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 172 eff = 0.994952
[15:39:39.576] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 160 eff = 0.99476
[15:39:39.577] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 169 eff = 0.995553
[15:39:39.577] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 160 eff = 0.995505
[15:39:39.577] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 159 eff = 0.997332
[15:39:39.577] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 160 eff = 0.998678
[15:39:39.578] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 170 eff = 0.99875
[15:39:41.680] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:39:41.680] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:39:41.680] INFO: ----------------------------------------------------------------------
[15:39:41.680] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:39:41.680] INFO: ----------------------------------------------------------------------
[15:39:41.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:39:41.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:39:41.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:39:41.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:39:41.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:39:41.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:39:41.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:39:41.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:39:41.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:39:41.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:39:41.680] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:39:41.681] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:39:41.698] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:39:42.366] INFO: Expecting 208000 events.
[15:39:59.728] INFO: 208000 events read in total (16835ms).
[15:39:59.750] INFO: Test took 18052ms.
[15:40:00.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:40:00.520] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4355509
[15:40:00.520] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:40:00.520] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:40:01.037] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 1 0
[15:40:01.037] INFO: number of red-efficiency pixels: 263 174 445 686 782 871 922 782 917 949 866 804 850 483 180 217
[15:40:01.037] INFO: number of X-ray hits detected: 170008 125244 205481 310422 326199 330626 333196 312780 339937 347658 338810 321484 321384 215881 132100 157741
[15:40:01.037] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:40:01.037] INFO: number of Vcal hits detected: 207727 207822 207507 207219 207090 206993 206887 207093 206944 206867 206955 206977 206988 207455 207767 207776
[15:40:01.037] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.6 99.5 99.6 99.5 99.5 99.5 99.6 99.6 99.8 99.9 99.9
[15:40:01.037] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.5 99.5 99.6 99.5 99.5 99.5 99.5 99.5 99.7 99.9 99.9
[15:40:01.037] INFO: X-ray hit rate [MHz/cm2]: 49.8 36.7 60.2 91.0 95.6 96.9 97.7 91.7 99.6 101.9 99.3 94.2 94.2 63.3 38.7 46.2
[15:40:01.037] INFO: PixTestHighRate::doXPixelAlive() done
[15:40:01.081] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:40:01.081] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:40:01.081] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:40:01.081] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:40:01.081] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:40:01.082] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:40:01.082] INFO: PixTest:: pg_setup set to default.
[15:40:04.848] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:40:04.848] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C7_V0 -> highRate_xraymap_mod
[15:40:07.048] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:40:07.049] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 386.7mA
[15:40:07.049] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.1mA
[15:40:07.049] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:40:07.049] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:40:07.049] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:40:07.050] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:40:07.050] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:40:07.050] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:40:07.050] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:40:07.050] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:40:07.050] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:40:07.050] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:40:07.050] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:40:07.143] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:40:07.143] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:40:07.152] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:40:07.152] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:40:07.152] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:40:07.152] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:40:07.152] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:40:07.152] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:40:07.155] QUIET: Connection to board 58 closed.
[15:40:07.234] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries