[14:57:19.700] INFO: *** Welcome to pxar ***
[14:57:19.700] INFO: *** Today: 2016/08/11
[14:57:19.722] INFO: *** Version: v1.9.0-816-gf0c0
[14:57:19.722] INFO: readRocDacs: data/mp633/dacParameters35_C0.dat .. data/mp633/dacParameters35_C15.dat
[14:57:19.723] INFO: readTbmDacs: data/mp633/tbmParameters_C0a.dat .. data/mp633/tbmParameters_C0b.dat
[14:57:19.723] INFO: readMaskFile: data/mp633/defaultMaskFile.dat
[14:57:19.724] INFO: readTrimFile: data/mp633/trimParameters35_C0.dat .. data/mp633/trimParameters35_C15.dat
[14:57:19.875] INFO: clk: 4
[14:57:19.876] INFO: ctr: 4
[14:57:19.876] INFO: sda: 19
[14:57:19.876] INFO: tin: 9
[14:57:19.876] INFO: level: 15
[14:57:19.876] INFO: triggerdelay: 0
[14:57:19.876] QUIET: Instanciating API for pxar v1.9.0+816~gf0c098f
[14:57:19.876] INFO: Log level: DEBUG
[14:57:19.889] QUIET: Connection to board DTB_WRPRHI opened.
[14:57:19.892] 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:
------------------------------------------------------
[14:57:19.895] INFO: RPC call hashes of host and DTB match: 398089610
[14:57:21.494] INFO: DUT info:
[14:57:21.494] INFO: The DUT currently contains the following objects:
[14:57:21.494] INFO: 2 TBM Cores tbm08c (2 ON)
[14:57:21.494] INFO: TBM Core alpha (0): 7 registers set
[14:57:21.494] INFO: TBM Core beta (1): 7 registers set
[14:57:21.494] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:57:21.494] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.494] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:57:21.495] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:57:21.496] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:57:21.497] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:57:21.499] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35532800
[14:57:21.499] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x23320e0
[14:57:21.499] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x20d4370
[14:57:21.499] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fc6a9d94010
[14:57:21.499] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fc6affff510
[14:57:21.499] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35540992 fPxarMemory = 0x7fc6a9d94010
[14:57:21.501] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 373.8mA
[14:57:21.502] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[14:57:21.502] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[14:57:21.993] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2720fd0
[14:57:22.057] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:57:22.057] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:57:22.057] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:57:22.063] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:57:22.063] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:57:22.063] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:57:22.088] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:57:22.088] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:57:22.105] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:57:22.105] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:57:22.123] INFO: PixTestCmd::init()
[14:57:22.136] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:57:22.136] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:57:22.136] INFO: readGainPedestalParameters data/mp633/phCalibrationFitErr35_C0.dat .. data/mp633/phCalibrationFitErr35_C15.dat
[14:57:22.385] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:57:22.385] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:57:22.403] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:57:22.410] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:57:22.410] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:57:22.410] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:57:22.440] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:57:22.440] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:57:22.440] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:57:22.440] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:57:22.440] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:57:22.448] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:57:22.448] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:57:22.472] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:57:22.472] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:57:22.472] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:57:22.472] INFO: readReadbackCal: data/mp633/readbackCal_C0.dat .. data/mp633/readbackCal_C15.dat
[14:57:22.491] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:57:22.500] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[14:57:22.500] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[14:57:22.500] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[14:57:22.500] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[14:57:22.523] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:57:22.523] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:57:28.740] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[14:57:32.116] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[14:57:32.116] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[14:57:32.116] INFO: ----------------------------------------------------------------------
[14:57:32.116] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[14:57:32.116] INFO: ----------------------------------------------------------------------
[14:57:33.089] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[14:57:44.902] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:57:44.906] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:58:09.374] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163875 events.
[14:58:16.575] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163875, pixels seen in all events: 6285741
[14:58:16.764] INFO: Resuming triggers.
[14:58:28.580] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:58:28.623] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:58:52.876] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1164127 events.
[14:59:00.004] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1164127, pixels seen in all events: 6282141
[14:59:00.189] INFO: Resuming triggers.
[14:59:12.009] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[14:59:12.039] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:59:36.623] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1164452 events.
[14:59:43.942] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1164452, pixels seen in all events: 6278045
[14:59:44.115] INFO: Resuming triggers.
[14:59:55.936] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[14:59:55.977] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:00:20.572] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1164549 events.
[15:00:27.861] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1164549, pixels seen in all events: 6276378
[15:00:28.047] INFO: Resuming triggers.
[15:00:39.868] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[15:00:39.916] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:01:04.367] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1164547 events.
[15:01:11.629] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1164547, pixels seen in all events: 6276865
[15:01:11.807] INFO: Resuming triggers.
[15:01:12.790] INFO: data taking finished, elapsed time: 60 seconds.
[15:01:12.987] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:01:15.166] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 96842 events.
[15:01:15.757] DEBUG: <PixTestXray.cc/processData:L823> # events read: 96842, pixels seen in all events: 522927
[15:01:15.805] INFO: PixTest:: pg_setup set to default.
[15:01:15.853] INFO: PixTestXray::doPhRun() done
[15:02:17.292] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:02:17.293] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377.8mA
[15:02:17.294] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 465.5mA
[15:02:17.294] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[15:02:17.294] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:02:17.294] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:02:17.294] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:02:17.294] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:02:17.294] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:02:17.294] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:02:17.294] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:02:17.294] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:02:17.294] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:02:17.294] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:02:17.294] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:02:17.294] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:02:17.294] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:02:17.294] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:02:17.294] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:02:17.295] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:02:17.295] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:02:17.295] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:02:17.572] QUIET: Connection to board 58 closed.
[15:02:17.651] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[15:02:28.688] INFO: *** Welcome to pxar ***
[15:02:28.688] INFO: *** Today: 2016/08/11
[15:02:28.726] INFO: *** Version: v1.9.0-816-gf0c0
[15:02:28.726] INFO: readRocDacs: data/mp633/dacParameters35_C0.dat .. data/mp633/dacParameters35_C15.dat
[15:02:28.727] INFO: readTbmDacs: data/mp633/tbmParameters_C0a.dat .. data/mp633/tbmParameters_C0b.dat
[15:02:28.727] INFO: readMaskFile: data/mp633/defaultMaskFile.dat
[15:02:28.727] INFO: readTrimFile: data/mp633/trimParameters35_C0.dat .. data/mp633/trimParameters35_C15.dat
[15:02:28.876] INFO: clk: 4
[15:02:28.876] INFO: ctr: 4
[15:02:28.876] INFO: sda: 19
[15:02:28.876] INFO: tin: 9
[15:02:28.876] INFO: level: 15
[15:02:28.876] INFO: triggerdelay: 0
[15:02:28.876] QUIET: Instanciating API for pxar v1.9.0+816~gf0c098f
[15:02:28.876] INFO: Log level: DEBUG
[15:02:28.887] QUIET: Connection to board DTB_WRPRHI opened.
[15:02:28.890] 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:02:28.893] INFO: RPC call hashes of host and DTB match: 398089610
[15:02:30.493] INFO: DUT info:
[15:02:30.493] INFO: The DUT currently contains the following objects:
[15:02:30.493] INFO: 2 TBM Cores tbm08c (2 ON)
[15:02:30.493] INFO: TBM Core alpha (0): 7 registers set
[15:02:30.493] INFO: TBM Core beta (1): 7 registers set
[15:02:30.493] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:02:30.493] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.493] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.494] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.494] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:02:30.494] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:02:30.495] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:02:30.496] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:02:30.498] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31506432
[15:02:30.498] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xdba0e0
[15:02:30.498] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xb5c370
[15:02:30.498] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8919d94010
[15:02:30.498] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f891ffff510
[15:02:30.498] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31514624 fPxarMemory = 0x7f8919d94010
[15:02:30.500] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 373.8mA
[15:02:30.501] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[15:02:30.501] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.5 C
[15:02:31.018] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x11a8f60
[15:02:31.079] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:02:31.080] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:02:31.080] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:02:31.085] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:02:31.085] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:02:31.085] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:02:31.109] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:02:31.109] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:02:31.121] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:02:31.121] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:02:31.133] INFO: PixTestCmd::init()
[15:02:31.145] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:02:31.145] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:02:31.145] INFO: readGainPedestalParameters data/mp633/phCalibrationFitErr35_C0.dat .. data/mp633/phCalibrationFitErr35_C15.dat
[15:02:31.392] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:02:31.392] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:02:31.406] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:02:31.412] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:02:31.412] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:02:31.412] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:02:31.436] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:02:31.436] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:02:31.436] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:02:31.436] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:02:31.436] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:02:31.443] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:02:31.443] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:02:31.466] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:02:31.466] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:02:31.466] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:02:31.467] INFO: readReadbackCal: data/mp633/readbackCal_C0.dat .. data/mp633/readbackCal_C15.dat
[15:02:31.476] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:02:31.483] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:02:31.486] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:02:31.486] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:02:31.486] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:02:31.505] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:02:31.505] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:02:35.380] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[15:02:36.308] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[15:02:36.308] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[15:02:36.308] INFO: ----------------------------------------------------------------------
[15:02:36.308] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[15:02:36.308] INFO: ----------------------------------------------------------------------
[15:02:37.281] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[15:02:45.205] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:02:45.209] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:03:08.533] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 780751 events.
[15:03:21.106] DEBUG: <PixTestXray.cc/processData:L823> # events read: 780751, pixels seen in all events: 10883790
[15:03:21.378] INFO: Resuming triggers.
[15:03:29.305] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:03:29.347] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:03:52.770] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 780859 events.
[15:04:05.422] DEBUG: <PixTestXray.cc/processData:L823> # events read: 780859, pixels seen in all events: 10881404
[15:04:05.628] INFO: Resuming triggers.
[15:04:13.559] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[15:04:13.609] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:04:36.992] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 781339 events.
[15:04:49.772] DEBUG: <PixTestXray.cc/processData:L823> # events read: 781339, pixels seen in all events: 10876136
[15:04:50.039] INFO: Resuming triggers.
[15:04:57.969] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[15:04:58.041] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:05:21.545] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 781227 events.
[15:05:34.189] DEBUG: <PixTestXray.cc/processData:L823> # events read: 781227, pixels seen in all events: 10877541
[15:05:34.433] INFO: Resuming triggers.
[15:05:42.365] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[15:05:42.406] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:06:05.753] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 781488 events.
[15:06:18.317] DEBUG: <PixTestXray.cc/processData:L823> # events read: 781488, pixels seen in all events: 10874474
[15:06:18.536] INFO: Resuming triggers.
[15:06:26.464] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[15:06:26.535] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:06:49.786] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 781020 events.
[15:07:02.985] DEBUG: <PixTestXray.cc/processData:L823> # events read: 781020, pixels seen in all events: 10878969
[15:07:03.319] INFO: Resuming triggers.
[15:07:11.250] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:07:11.301] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:07:35.182] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 781305 events.
[15:07:48.170] DEBUG: <PixTestXray.cc/processData:L823> # events read: 781305, pixels seen in all events: 10876765
[15:07:48.415] INFO: Resuming triggers.
[15:07:52.990] INFO: data taking finished, elapsed time: 60 seconds.
[15:07:53.186] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[15:08:06.637] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 450682 events.
[15:08:13.968] DEBUG: <PixTestXray.cc/processData:L823> # events read: 450682, pixels seen in all events: 6266548
[15:08:14.076] INFO: PixTest:: pg_setup set to default.
[15:08:14.117] INFO: PixTestXray::doPhRun() done
[15:08:24.756] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:08:24.757] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377mA
[15:08:24.758] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[15:08:24.758] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[15:08:24.758] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:08:24.758] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:08:24.758] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:08:24.758] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:08:24.758] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:08:24.758] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:08:24.758] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:08:24.758] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:08:24.758] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:08:24.758] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:08:24.758] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:08:24.759] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:08:24.759] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:08:24.759] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:08:24.759] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:08:24.759] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:08:24.759] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:08:24.759] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:08:25.064] QUIET: Connection to board 58 closed.
[15:08:25.144] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[14:51:34.920] INFO: *** Welcome to pxar ***
[14:51:34.920] INFO: *** Today: 2016/08/11
[14:51:34.943] INFO: *** Version: v1.9.0-816-gf0c0
[14:51:34.943] INFO: readRocDacs: data/mp633/dacParameters35_C0.dat .. data/mp633/dacParameters35_C15.dat
[14:51:34.944] INFO: readTbmDacs: data/mp633/tbmParameters_C0a.dat .. data/mp633/tbmParameters_C0b.dat
[14:51:34.944] INFO: readMaskFile: data/mp633/defaultMaskFile.dat
[14:51:34.945] INFO: readTrimFile: data/mp633/trimParameters35_C0.dat .. data/mp633/trimParameters35_C15.dat
[14:51:35.097] INFO: clk: 4
[14:51:35.097] INFO: ctr: 4
[14:51:35.097] INFO: sda: 19
[14:51:35.097] INFO: tin: 9
[14:51:35.097] INFO: level: 15
[14:51:35.097] INFO: triggerdelay: 0
[14:51:35.097] QUIET: Instanciating API for pxar v1.9.0+816~gf0c098f
[14:51:35.097] INFO: Log level: DEBUG
[14:51:35.109] QUIET: Connection to board DTB_WRPRHI opened.
[14:51:35.112] 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:
------------------------------------------------------
[14:51:35.115] INFO: RPC call hashes of host and DTB match: 398089610
[14:51:36.714] INFO: DUT info:
[14:51:36.714] INFO: The DUT currently contains the following objects:
[14:51:36.714] INFO: 2 TBM Cores tbm08c (2 ON)
[14:51:36.714] INFO: TBM Core alpha (0): 7 registers set
[14:51:36.714] INFO: TBM Core beta (1): 7 registers set
[14:51:36.714] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:51:36.715] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:51:36.715] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:51:36.716] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:51:36.717] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:51:36.718] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:51:36.718] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:51:36.720] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34816000
[14:51:36.720] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x25740e0
[14:51:36.720] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2316370
[14:51:36.720] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7ff999d94010
[14:51:36.720] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7ff99ffff510
[14:51:36.720] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34824192 fPxarMemory = 0x7ff999d94010
[14:51:36.721] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 373.8mA
[14:51:36.722] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[14:51:36.722] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[14:51:37.236] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2962f10
[14:51:37.299] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:51:37.299] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:51:37.299] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:51:37.305] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:51:37.305] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:51:37.305] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:51:37.329] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:51:37.333] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:51:37.350] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:51:37.350] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:51:37.362] INFO: PixTestCmd::init()
[14:51:37.374] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:51:37.374] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:51:37.374] INFO: readGainPedestalParameters data/mp633/phCalibrationFitErr35_C0.dat .. data/mp633/phCalibrationFitErr35_C15.dat
[14:51:37.631] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:51:37.631] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:51:37.646] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:51:37.651] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:51:37.652] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:51:37.652] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:51:37.671] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:51:37.671] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:51:37.671] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:51:37.671] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:51:37.671] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:51:37.683] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:51:37.683] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:51:37.705] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:51:37.705] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:51:37.705] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:51:37.705] INFO: readReadbackCal: data/mp633/readbackCal_C0.dat .. data/mp633/readbackCal_C15.dat
[14:51:37.714] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:51:37.722] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[14:51:37.722] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[14:51:37.722] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[14:51:37.722] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[14:51:37.741] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:51:37.742] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:51:46.855] INFO: ######################################################################
[14:51:46.855] INFO: PixTestAlive::doTest()
[14:51:46.855] INFO: ######################################################################
[14:51:46.859] INFO: ----------------------------------------------------------------------
[14:51:46.859] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:51:46.859] INFO: ----------------------------------------------------------------------
[14:51:46.861] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:47.236] INFO: Expecting 41600 events.
[14:51:51.514] INFO: 41600 events read in total (3560ms).
[14:51:51.659] INFO: Test took 4798ms.
[14:51:51.670] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:51.670] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:51:51.670] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:51:51.964] INFO: PixTestAlive::aliveTest() done
[14:51:51.964] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:51:51.964] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 19 18 34 36 26 45 43 33 39 37 36 37 35 27 13 16
[14:51:51.988] INFO: ----------------------------------------------------------------------
[14:51:51.988] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:51:51.988] INFO: ----------------------------------------------------------------------
[14:51:51.990] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:52.365] INFO: Expecting 41600 events.
[14:51:55.458] INFO: 41600 events read in total (2378ms).
[14:51:55.459] INFO: Test took 3469ms.
[14:51:55.459] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:55.459] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:51:55.459] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:51:55.459] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:51:55.886] INFO: PixTestAlive::maskTest() done
[14:51:55.886] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:51:55.913] INFO: ----------------------------------------------------------------------
[14:51:55.913] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:51:55.913] INFO: ----------------------------------------------------------------------
[14:51:55.916] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:51:56.293] INFO: Expecting 41600 events.
[14:52:00.538] INFO: 41600 events read in total (3530ms).
[14:52:00.540] INFO: Test took 4625ms.
[14:52:00.550] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:00.550] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:52:00.550] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:52:00.923] INFO: PixTestAlive::addressDecodingTest() done
[14:52:00.923] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:52:00.923] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[14:52:56.612] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:52:56.612] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:52:56.612] INFO: ----------------------------------------------------------------------
[14:52:56.612] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:52:56.612] INFO: ----------------------------------------------------------------------
[14:52:56.751] INFO: Expecting 768 events.
[14:52:57.885] INFO: 768 events read in total (418ms).
[14:52:57.885] INFO: Test took 1267ms.
[14:52:57.891] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:52:58.689] INFO: Expecting 41600 events.
[14:53:01.896] INFO: 41600 events read in total (2680ms).
[14:53:01.900] INFO: Test took 4009ms.
[14:53:01.983] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:01.983] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 457089
[14:53:01.983] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:53:01.983] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:02.023] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:02.691] INFO: Expecting 41600 events.
[14:53:05.992] INFO: 41600 events read in total (2775ms).
[14:53:05.996] INFO: Test took 3973ms.
[14:53:06.073] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:06.073] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463135
[14:53:06.073] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:53:06.073] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:06.112] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:06.778] INFO: Expecting 41600 events.
[14:53:10.110] INFO: 41600 events read in total (2805ms).
[14:53:10.114] INFO: Test took 4002ms.
[14:53:10.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:10.192] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465324
[14:53:10.192] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:53:10.193] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:10.232] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:10.898] INFO: Expecting 41600 events.
[14:53:14.217] INFO: 41600 events read in total (2793ms).
[14:53:14.220] INFO: Test took 3988ms.
[14:53:14.297] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:14.297] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465436
[14:53:14.297] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:53:14.298] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:14.336] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:15.001] INFO: Expecting 41600 events.
[14:53:18.330] INFO: 41600 events read in total (2802ms).
[14:53:18.333] INFO: Test took 3997ms.
[14:53:18.411] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:18.411] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465756
[14:53:18.411] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:53:18.411] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:18.450] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:19.115] INFO: Expecting 41600 events.
[14:53:22.456] INFO: 41600 events read in total (2814ms).
[14:53:22.460] INFO: Test took 4010ms.
[14:53:22.536] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:22.536] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465854
[14:53:22.536] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:53:22.537] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:22.576] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:23.241] INFO: Expecting 41600 events.
[14:53:26.579] INFO: 41600 events read in total (2811ms).
[14:53:26.582] INFO: Test took 4006ms.
[14:53:26.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:26.660] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464820
[14:53:26.660] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:53:26.660] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:26.699] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:27.364] INFO: Expecting 41600 events.
[14:53:30.708] INFO: 41600 events read in total (2817ms).
[14:53:30.711] INFO: Test took 4012ms.
[14:53:30.788] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:30.788] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465273
[14:53:30.788] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:53:30.789] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:30.827] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:31.493] INFO: Expecting 41600 events.
[14:53:34.855] INFO: 41600 events read in total (2836ms).
[14:53:34.858] INFO: Test took 4032ms.
[14:53:34.935] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:34.935] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464993
[14:53:34.935] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:53:34.936] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:34.975] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:35.640] INFO: Expecting 41600 events.
[14:53:38.971] INFO: 41600 events read in total (2805ms).
[14:53:38.975] INFO: Test took 4000ms.
[14:53:39.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:39.051] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465718
[14:53:39.051] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:53:39.052] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:39.091] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:39.756] INFO: Expecting 41600 events.
[14:53:43.080] INFO: 41600 events read in total (2797ms).
[14:53:43.084] INFO: Test took 3993ms.
[14:53:43.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:43.162] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465402
[14:53:43.162] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:53:43.162] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:43.201] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:43.867] INFO: Expecting 41600 events.
[14:53:47.216] INFO: 41600 events read in total (2823ms).
[14:53:47.220] INFO: Test took 4018ms.
[14:53:47.298] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:47.298] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465407
[14:53:47.298] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:53:47.298] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:47.337] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:48.003] INFO: Expecting 41600 events.
[14:53:51.335] INFO: 41600 events read in total (2806ms).
[14:53:51.338] INFO: Test took 4000ms.
[14:53:51.417] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:51.417] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 463951
[14:53:51.417] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:53:51.417] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:51.458] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:52.123] INFO: Expecting 41600 events.
[14:53:55.457] INFO: 41600 events read in total (2807ms).
[14:53:55.460] INFO: Test took 4002ms.
[14:53:55.537] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:55.537] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465477
[14:53:55.537] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:53:55.537] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:55.577] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:53:56.242] INFO: Expecting 41600 events.
[14:53:59.576] INFO: 41600 events read in total (2807ms).
[14:53:59.579] INFO: Test took 4002ms.
[14:53:59.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:53:59.656] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 465953
[14:53:59.656] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:53:59.656] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:53:59.696] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:00.361] INFO: Expecting 41600 events.
[14:54:03.715] INFO: 41600 events read in total (2827ms).
[14:54:03.718] INFO: Test took 4022ms.
[14:54:03.794] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:03.795] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464728
[14:54:03.795] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:54:03.795] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:03.834] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:04.499] INFO: Expecting 41600 events.
[14:54:07.833] INFO: 41600 events read in total (2807ms).
[14:54:07.836] INFO: Test took 4002ms.
[14:54:07.914] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:07.914] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 466103
[14:54:07.914] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:54:07.914] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:07.954] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:08.619] INFO: Expecting 41600 events.
[14:54:11.967] INFO: 41600 events read in total (2821ms).
[14:54:11.970] INFO: Test took 4016ms.
[14:54:12.048] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:12.048] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464311
[14:54:12.048] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:54:12.048] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:12.088] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:12.753] INFO: Expecting 41600 events.
[14:54:16.047] INFO: 41600 events read in total (2768ms).
[14:54:16.051] INFO: Test took 3963ms.
[14:54:16.127] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:16.127] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 464049
[14:54:16.127] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:54:16.128] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:16.167] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:16.832] INFO: Expecting 41600 events.
[14:54:19.966] INFO: 41600 events read in total (2607ms).
[14:54:19.970] INFO: Test took 3803ms.
[14:54:20.046] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:20.046] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 457065
[14:54:20.046] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:54:20.046] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:20.306] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 142 eff = 0.999639
[14:54:20.306] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 182 eff = 0.999784
[14:54:20.307] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 173 eff = 0.999399
[14:54:20.307] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 161 eff = 0.998942
[14:54:20.307] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 147 eff = 0.999135
[14:54:20.307] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 160 eff = 0.998822
[14:54:20.308] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 148 eff = 0.99887
[14:54:20.308] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 157 eff = 0.999183
[14:54:20.308] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 161 eff = 0.99899
[14:54:20.308] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 150 eff = 0.99875
[14:54:20.309] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 151 eff = 0.999207
[14:54:20.309] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 138 eff = 0.999038
[14:54:20.309] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 159 eff = 0.999038
[14:54:20.309] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 151 eff = 0.999471
[14:54:20.310] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 151 eff = 0.999736
[14:54:20.310] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 158 eff = 0.99976
[14:54:22.764] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:54:22.764] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:54:22.764] INFO: ----------------------------------------------------------------------
[14:54:22.764] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:54:22.764] INFO: ----------------------------------------------------------------------
[14:54:22.764] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:54:22.765] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:54:22.765] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:54:22.765] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:54:22.765] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:54:22.765] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:54:22.765] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:54:22.765] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:54:22.765] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:54:22.765] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:54:22.765] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:54:22.765] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:54:22.781] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:54:23.447] INFO: Expecting 208000 events.
[14:54:36.010] INFO: 208000 events read in total (12036ms).
[14:54:36.022] INFO: Test took 13241ms.
[14:54:36.474] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:54:36.474] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2045299
[14:54:36.474] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:54:36.474] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:54:36.840] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:54:36.840] INFO: number of red-efficiency pixels: 103 81 139 232 244 228 280 221 296 243 264 314 215 159 100 72
[14:54:36.840] INFO: number of X-ray hits detected: 72885 61343 99948 149052 156157 153857 153596 143493 158910 157815 156857 146510 147979 102126 60793 57418
[14:54:36.840] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:54:36.840] INFO: number of Vcal hits detected: 207894 207916 207855 207717 207744 207766 207708 207770 207675 207750 207727 207672 207776 207833 207897 207927
[14:54:36.840] INFO: Vcal hit fiducial efficiency (%): 100.0 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
[14:54:36.840] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.8 99.9 99.9 100.0 100.0
[14:54:36.840] INFO: X-ray hit rate [MHz/cm2]: 21.4 18.0 29.3 43.7 45.8 45.1 45.0 42.1 46.6 46.3 46.0 42.9 43.4 29.9 17.8 16.8
[14:54:36.840] INFO: PixTestHighRate::doXPixelAlive() done
[14:54:36.886] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:54:36.886] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:54:36.886] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:54:36.886] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:54:36.886] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:54:36.886] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:54:36.886] INFO: PixTest:: pg_setup set to default.
[14:54:41.308] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:54:41.309] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377.8mA
[14:54:41.310] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[14:54:41.310] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[14:54:41.310] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:54:41.310] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:54:41.310] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:54:41.310] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:54:41.310] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:54:41.310] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:54:41.310] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:54:41.310] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:54:41.310] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:54:41.310] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:54:41.387] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:54:41.387] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:54:41.396] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:54:41.396] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:54:41.396] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:54:41.396] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[14:54:41.396] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:54:41.396] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:54:41.399] QUIET: Connection to board 58 closed.
[14:54:41.478] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[14:48:47.960] INFO: *** Welcome to pxar ***
[14:48:47.960] INFO: *** Today: 2016/08/11
[14:48:47.998] INFO: *** Version: v1.9.0-816-gf0c0
[14:48:47.998] INFO: readRocDacs: data/mp633/dacParameters35_C0.dat .. data/mp633/dacParameters35_C15.dat
[14:48:47.999] INFO: readTbmDacs: data/mp633/tbmParameters_C0a.dat .. data/mp633/tbmParameters_C0b.dat
[14:48:47.999] INFO: readMaskFile: data/mp633/defaultMaskFile.dat
[14:48:47.999] INFO: readTrimFile: data/mp633/trimParameters35_C0.dat .. data/mp633/trimParameters35_C15.dat
[14:48:48.149] INFO: clk: 4
[14:48:48.149] INFO: ctr: 4
[14:48:48.149] INFO: sda: 19
[14:48:48.149] INFO: tin: 9
[14:48:48.149] INFO: level: 15
[14:48:48.149] INFO: triggerdelay: 0
[14:48:48.149] QUIET: Instanciating API for pxar v1.9.0+816~gf0c098f
[14:48:48.149] INFO: Log level: DEBUG
[14:48:48.161] QUIET: Connection to board DTB_WRPRHI opened.
[14:48:48.164] 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:
------------------------------------------------------
[14:48:48.167] INFO: RPC call hashes of host and DTB match: 398089610
[14:48:49.766] INFO: DUT info:
[14:48:49.766] INFO: The DUT currently contains the following objects:
[14:48:49.766] INFO: 2 TBM Cores tbm08c (2 ON)
[14:48:49.766] INFO: TBM Core alpha (0): 7 registers set
[14:48:49.766] INFO: TBM Core beta (1): 7 registers set
[14:48:49.766] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:48:49.766] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.766] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:48:49.767] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:48:49.768] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:48:49.769] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:48:49.772] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33452032
[14:48:49.772] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x13890e0
[14:48:49.772] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x112b370
[14:48:49.772] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fd4c1d94010
[14:48:49.772] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fd4c7fff510
[14:48:49.772] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33460224 fPxarMemory = 0x7fd4c1d94010
[14:48:49.773] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 373mA
[14:48:49.774] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[14:48:49.774] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[14:48:50.267] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1777fd0
[14:48:50.335] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:48:50.335] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:48:50.335] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:48:50.341] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:48:50.341] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:48:50.341] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:48:50.364] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:48:50.365] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:48:50.376] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:48:50.376] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:48:50.393] INFO: PixTestCmd::init()
[14:48:50.402] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:48:50.403] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:48:50.403] INFO: readGainPedestalParameters data/mp633/phCalibrationFitErr35_C0.dat .. data/mp633/phCalibrationFitErr35_C15.dat
[14:48:50.654] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:48:50.654] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:48:50.669] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:48:50.674] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:48:50.674] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:48:50.674] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:48:50.693] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:48:50.693] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:48:50.693] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:48:50.693] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:48:50.693] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:48:50.707] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:48:50.707] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:48:50.728] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:48:50.728] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:48:50.728] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:48:50.728] INFO: readReadbackCal: data/mp633/readbackCal_C0.dat .. data/mp633/readbackCal_C15.dat
[14:48:50.737] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:48:50.745] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[14:48:50.745] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[14:48:50.745] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[14:48:50.745] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[14:48:50.766] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:48:50.766] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:48:54.319] INFO: ######################################################################
[14:48:54.319] INFO: PixTestAlive::doTest()
[14:48:54.319] INFO: ######################################################################
[14:48:54.323] INFO: ----------------------------------------------------------------------
[14:48:54.323] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:48:54.323] INFO: ----------------------------------------------------------------------
[14:48:54.325] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:54.701] INFO: Expecting 41600 events.
[14:48:58.963] INFO: 41600 events read in total (3544ms).
[14:48:59.112] INFO: Test took 4787ms.
[14:48:59.123] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:59.123] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:48:59.123] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:48:59.403] INFO: PixTestAlive::aliveTest() done
[14:48:59.403] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:48:59.404] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 38 22 44 54 67 62 67 56 72 65 63 58 61 39 35 24
[14:48:59.426] INFO: ----------------------------------------------------------------------
[14:48:59.427] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:48:59.427] INFO: ----------------------------------------------------------------------
[14:48:59.429] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:59.804] INFO: Expecting 41600 events.
[14:49:02.859] INFO: 41600 events read in total (2340ms).
[14:49:02.859] INFO: Test took 3430ms.
[14:49:02.859] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:02.859] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:49:02.859] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:49:02.860] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:49:03.283] INFO: PixTestAlive::maskTest() done
[14:49:03.283] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:49:03.305] INFO: ----------------------------------------------------------------------
[14:49:03.305] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:49:03.305] INFO: ----------------------------------------------------------------------
[14:49:03.307] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:03.684] INFO: Expecting 41600 events.
[14:49:07.932] INFO: 41600 events read in total (3532ms).
[14:49:07.934] INFO: Test took 4627ms.
[14:49:07.945] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:07.945] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:49:07.945] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:49:08.322] INFO: PixTestAlive::addressDecodingTest() done
[14:49:08.322] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:49:08.322] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[14:49:11.620] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:49:11.620] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:49:11.620] INFO: ----------------------------------------------------------------------
[14:49:11.620] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:49:11.620] INFO: ----------------------------------------------------------------------
[14:49:11.759] INFO: Expecting 768 events.
[14:49:12.892] INFO: 768 events read in total (417ms).
[14:49:12.892] INFO: Test took 1266ms.
[14:49:12.898] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:13.695] INFO: Expecting 41600 events.
[14:49:17.129] INFO: 41600 events read in total (2907ms).
[14:49:17.134] INFO: Test took 4236ms.
[14:49:17.256] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:17.256] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 678005
[14:49:17.256] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:49:17.256] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:17.309] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:17.974] INFO: Expecting 41600 events.
[14:49:21.516] INFO: 41600 events read in total (3015ms).
[14:49:21.521] INFO: Test took 4212ms.
[14:49:21.637] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:21.637] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 686500
[14:49:21.638] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:49:21.638] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:21.691] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:22.356] INFO: Expecting 41600 events.
[14:49:25.918] INFO: 41600 events read in total (3035ms).
[14:49:25.923] INFO: Test took 4232ms.
[14:49:26.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:26.039] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688580
[14:49:26.039] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:49:26.040] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:26.093] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:26.758] INFO: Expecting 41600 events.
[14:49:30.324] INFO: 41600 events read in total (3039ms).
[14:49:30.328] INFO: Test took 4235ms.
[14:49:30.444] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:30.444] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688160
[14:49:30.444] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:49:30.445] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:30.498] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:31.163] INFO: Expecting 41600 events.
[14:49:34.720] INFO: 41600 events read in total (3030ms).
[14:49:34.725] INFO: Test took 4227ms.
[14:49:34.842] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:34.842] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688615
[14:49:34.842] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:49:34.842] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:34.896] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:35.561] INFO: Expecting 41600 events.
[14:49:39.154] INFO: 41600 events read in total (3066ms).
[14:49:39.159] INFO: Test took 4263ms.
[14:49:39.276] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:39.276] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 689682
[14:49:39.276] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:49:39.277] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:39.330] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:39.996] INFO: Expecting 41600 events.
[14:49:43.612] INFO: 41600 events read in total (3089ms).
[14:49:43.617] INFO: Test took 4287ms.
[14:49:43.735] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:43.735] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 687940
[14:49:43.735] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:49:43.735] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:43.790] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:44.457] INFO: Expecting 41600 events.
[14:49:48.036] INFO: 41600 events read in total (3052ms).
[14:49:48.041] INFO: Test took 4251ms.
[14:49:48.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:48.163] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688493
[14:49:48.163] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:49:48.163] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:48.218] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:48.888] INFO: Expecting 41600 events.
[14:49:52.558] INFO: 41600 events read in total (3143ms).
[14:49:52.563] INFO: Test took 4345ms.
[14:49:52.679] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:52.679] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688405
[14:49:52.679] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:49:52.679] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:52.733] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:53.398] INFO: Expecting 41600 events.
[14:49:57.025] INFO: 41600 events read in total (3100ms).
[14:49:57.030] INFO: Test took 4297ms.
[14:49:57.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:57.149] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688654
[14:49:57.149] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:49:57.150] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:57.207] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:57.875] INFO: Expecting 41600 events.
[14:50:01.477] INFO: 41600 events read in total (3076ms).
[14:50:01.482] INFO: Test took 4275ms.
[14:50:01.601] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:01.601] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 689527
[14:50:01.601] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:50:01.602] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:01.657] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:02.323] INFO: Expecting 41600 events.
[14:50:05.932] INFO: 41600 events read in total (3082ms).
[14:50:05.937] INFO: Test took 4280ms.
[14:50:06.052] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:06.052] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688560
[14:50:06.052] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:50:06.052] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:06.107] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:06.772] INFO: Expecting 41600 events.
[14:50:10.370] INFO: 41600 events read in total (3071ms).
[14:50:10.374] INFO: Test took 4267ms.
[14:50:10.489] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:10.489] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688047
[14:50:10.489] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:50:10.490] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:10.544] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:11.209] INFO: Expecting 41600 events.
[14:50:14.811] INFO: 41600 events read in total (3075ms).
[14:50:14.816] INFO: Test took 4272ms.
[14:50:14.931] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:14.931] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688334
[14:50:14.931] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:50:14.932] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:14.986] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:15.651] INFO: Expecting 41600 events.
[14:50:19.240] INFO: 41600 events read in total (3062ms).
[14:50:19.244] INFO: Test took 4258ms.
[14:50:19.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:19.360] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685795
[14:50:19.360] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:50:19.361] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:19.415] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:20.080] INFO: Expecting 41600 events.
[14:50:23.677] INFO: 41600 events read in total (3070ms).
[14:50:23.681] INFO: Test took 4266ms.
[14:50:23.798] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:23.798] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688325
[14:50:23.798] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:50:23.799] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:23.853] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:24.518] INFO: Expecting 41600 events.
[14:50:28.195] INFO: 41600 events read in total (3150ms).
[14:50:28.202] INFO: Test took 4349ms.
[14:50:28.324] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:28.324] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 688160
[14:50:28.324] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:50:28.324] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:28.380] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:29.047] INFO: Expecting 41600 events.
[14:50:32.673] INFO: 41600 events read in total (3100ms).
[14:50:32.677] INFO: Test took 4297ms.
[14:50:32.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:32.821] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 686790
[14:50:32.821] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:50:32.821] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:32.875] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:33.541] INFO: Expecting 41600 events.
[14:50:37.187] INFO: 41600 events read in total (3120ms).
[14:50:37.192] INFO: Test took 4317ms.
[14:50:37.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:37.312] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684450
[14:50:37.312] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:50:37.312] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:37.368] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:38.037] INFO: Expecting 41600 events.
[14:50:41.499] INFO: 41600 events read in total (2936ms).
[14:50:41.504] INFO: Test took 4135ms.
[14:50:41.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:41.617] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 677220
[14:50:41.617] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:50:41.617] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:41.892] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 154 eff = 0.999279
[14:50:41.892] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 182 eff = 0.999519
[14:50:41.892] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 174 eff = 0.998438
[14:50:41.892] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 158 eff = 0.997548
[14:50:41.892] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 153 eff = 0.997139
[14:50:41.893] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 160 eff = 0.997188
[14:50:41.893] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 160 eff = 0.997981
[14:50:41.893] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 157 eff = 0.997861
[14:50:41.893] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 159 eff = 0.997957
[14:50:41.893] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 152 eff = 0.997692
[14:50:41.893] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 149 eff = 0.997572
[14:50:41.893] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 159 eff = 0.99762
[14:50:41.894] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 159 eff = 0.997981
[14:50:41.894] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 164 eff = 0.998798
[14:50:41.894] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 142 eff = 0.999447
[14:50:41.894] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 145 eff = 0.999639
[14:50:52.756] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:50:52.756] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:50:52.756] INFO: ----------------------------------------------------------------------
[14:50:52.756] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:50:52.756] INFO: ----------------------------------------------------------------------
[14:50:52.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:50:52.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:50:52.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:50:52.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:50:52.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:50:52.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:50:52.756] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:50:52.757] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:50:52.757] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:50:52.757] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:50:52.757] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:50:52.757] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:50:52.774] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:53.440] INFO: Expecting 208000 events.
[14:51:07.881] INFO: 208000 events read in total (13915ms).
[14:51:07.899] INFO: Test took 15125ms.
[14:51:08.600] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:08.600] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3142498
[14:51:08.600] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:51:08.600] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:09.044] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:51:09.044] INFO: number of red-efficiency pixels: 160 155 297 473 525 470 475 431 568 499 574 470 444 288 167 163
[14:51:09.044] INFO: number of X-ray hits detected: 113359 95087 156847 232077 244114 238905 237679 222569 247073 244465 242647 227308 230576 159436 94579 89217
[14:51:09.044] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:51:09.044] INFO: number of Vcal hits detected: 207832 207839 207685 207478 207411 207485 207486 207529 207368 207463 207361 207492 207524 207690 207815 207833
[14:51:09.044] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.8 99.8 99.8 99.7 99.8 99.7 99.8 99.8 99.9 99.9 99.9
[14:51:09.045] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.8 99.8 99.8 99.7 99.7 99.7 99.8 99.8 99.9 99.9 99.9
[14:51:09.045] INFO: X-ray hit rate [MHz/cm2]: 33.2 27.9 46.0 68.0 71.6 70.0 69.7 65.2 72.4 71.7 71.1 66.6 67.6 46.7 27.7 26.2
[14:51:09.045] INFO: PixTestHighRate::doXPixelAlive() done
[14:51:09.094] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:51:09.094] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:51:09.094] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:51:09.094] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:51:09.094] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:51:09.094] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:51:09.094] INFO: PixTest:: pg_setup set to default.
[14:51:19.428] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:51:19.429] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377.8mA
[14:51:19.429] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[14:51:19.429] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[14:51:19.430] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:51:19.430] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:51:19.430] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:51:19.430] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:51:19.430] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:51:19.430] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:51:19.430] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:51:19.430] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:51:19.430] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:51:19.430] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:51:19.511] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:51:19.511] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:51:19.520] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:51:19.520] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:51:19.520] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:51:19.520] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[14:51:19.520] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:51:19.520] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:51:19.523] QUIET: Connection to board 58 closed.
[14:51:19.603] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[14:43:40.944] INFO: *** Welcome to pxar ***
[14:43:40.944] INFO: *** Today: 2016/08/11
[14:43:40.967] INFO: *** Version: v1.9.0-816-gf0c0
[14:43:40.967] INFO: readRocDacs: data/mp633/dacParameters35_C0.dat .. data/mp633/dacParameters35_C15.dat
[14:43:40.968] INFO: readTbmDacs: data/mp633/tbmParameters_C0a.dat .. data/mp633/tbmParameters_C0b.dat
[14:43:40.968] INFO: readMaskFile: data/mp633/defaultMaskFile.dat
[14:43:40.968] INFO: readTrimFile: data/mp633/trimParameters35_C0.dat .. data/mp633/trimParameters35_C15.dat
[14:43:41.119] INFO: clk: 4
[14:43:41.119] INFO: ctr: 4
[14:43:41.119] INFO: sda: 19
[14:43:41.119] INFO: tin: 9
[14:43:41.119] INFO: level: 15
[14:43:41.119] INFO: triggerdelay: 0
[14:43:41.119] QUIET: Instanciating API for pxar v1.9.0+816~gf0c098f
[14:43:41.119] INFO: Log level: DEBUG
[14:43:41.131] QUIET: Connection to board DTB_WRPRHI opened.
[14:43:41.135] 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:
------------------------------------------------------
[14:43:41.137] INFO: RPC call hashes of host and DTB match: 398089610
[14:43:42.737] INFO: DUT info:
[14:43:42.737] INFO: The DUT currently contains the following objects:
[14:43:42.737] INFO: 2 TBM Cores tbm08c (2 ON)
[14:43:42.737] INFO: TBM Core alpha (0): 7 registers set
[14:43:42.737] INFO: TBM Core beta (1): 7 registers set
[14:43:42.737] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:43:42.737] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.737] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:43:42.738] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:43:42.739] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:43:42.740] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:43:42.742] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 34811904
[14:43:42.742] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x26330e0
[14:43:42.742] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x23d5370
[14:43:42.742] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fa7bdd94010
[14:43:42.742] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fa7c3fff510
[14:43:42.742] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 34820096 fPxarMemory = 0x7fa7bdd94010
[14:43:42.743] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 373.8mA
[14:43:42.745] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 463.9mA
[14:43:42.745] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -1.6 C
[14:43:43.274] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2a21fd0
[14:43:43.335] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:43:43.335] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[14:43:43.336] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[14:43:43.341] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[14:43:43.341] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[14:43:43.341] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[14:43:43.364] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[14:43:43.368] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[14:43:43.379] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[14:43:43.379] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[14:43:43.391] INFO: PixTestCmd::init()
[14:43:43.408] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[14:43:43.408] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[14:43:43.408] INFO: readGainPedestalParameters data/mp633/phCalibrationFitErr35_C0.dat .. data/mp633/phCalibrationFitErr35_C15.dat
[14:43:43.660] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[14:43:43.660] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[14:43:43.675] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[14:43:43.681] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:43:43.681] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:43:43.681] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:43:43.700] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[14:43:43.700] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[14:43:43.700] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[14:43:43.700] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[14:43:43.700] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[14:43:43.712] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:43:43.712] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:43:43.735] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[14:43:43.735] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[14:43:43.735] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[14:43:43.735] INFO: readReadbackCal: data/mp633/readbackCal_C0.dat .. data/mp633/readbackCal_C15.dat
[14:43:43.745] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[14:43:43.752] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[14:43:43.752] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[14:43:43.752] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[14:43:43.752] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[14:43:43.772] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:43:43.772] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:43:47.239] INFO: ######################################################################
[14:43:47.239] INFO: PixTestAlive::doTest()
[14:43:47.239] INFO: ######################################################################
[14:43:47.242] INFO: ----------------------------------------------------------------------
[14:43:47.242] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:43:47.242] INFO: ----------------------------------------------------------------------
[14:43:47.245] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:43:47.620] INFO: Expecting 41600 events.
[14:43:51.879] INFO: 41600 events read in total (3541ms).
[14:43:52.027] INFO: Test took 4782ms.
[14:43:52.037] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:52.037] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:43:52.037] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:43:52.314] INFO: PixTestAlive::aliveTest() done
[14:43:52.314] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:43:52.314] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 34 23 53 68 79 67 71 60 84 75 68 57 59 37 24 34
[14:43:52.337] INFO: ----------------------------------------------------------------------
[14:43:52.337] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:43:52.337] INFO: ----------------------------------------------------------------------
[14:43:52.339] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:43:52.714] INFO: Expecting 41600 events.
[14:43:55.760] INFO: 41600 events read in total (2331ms).
[14:43:55.761] INFO: Test took 3422ms.
[14:43:55.761] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:55.761] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[14:43:55.761] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[14:43:55.761] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:43:56.184] INFO: PixTestAlive::maskTest() done
[14:43:56.184] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:43:56.204] INFO: ----------------------------------------------------------------------
[14:43:56.204] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:43:56.204] INFO: ----------------------------------------------------------------------
[14:43:56.206] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:43:56.580] INFO: Expecting 41600 events.
[14:44:00.834] INFO: 41600 events read in total (3538ms).
[14:44:00.835] INFO: Test took 4629ms.
[14:44:00.847] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:00.847] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[14:44:00.847] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[14:44:01.218] INFO: PixTestAlive::addressDecodingTest() done
[14:44:01.218] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:44:01.218] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[14:44:06.420] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[14:44:06.420] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:44:06.420] INFO: ----------------------------------------------------------------------
[14:44:06.420] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:44:06.420] INFO: ----------------------------------------------------------------------
[14:44:06.559] INFO: Expecting 768 events.
[14:44:07.691] INFO: 768 events read in total (416ms).
[14:44:07.692] INFO: Test took 1266ms.
[14:44:07.698] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:44:08.496] INFO: Expecting 41600 events.
[14:44:12.109] INFO: 41600 events read in total (3086ms).
[14:44:12.114] INFO: Test took 4416ms.
[14:44:12.260] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:12.260] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 830051
[14:44:12.260] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:44:12.260] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:44:12.323] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:44:12.990] INFO: Expecting 41600 events.
[14:44:16.702] INFO: 41600 events read in total (3185ms).
[14:44:16.707] INFO: Test took 4384ms.
[14:44:16.844] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:16.844] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 837338
[14:44:16.844] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:44:16.844] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:44:16.908] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:44:17.573] INFO: Expecting 41600 events.
[14:44:21.311] INFO: 41600 events read in total (3211ms).
[14:44:21.316] INFO: Test took 4408ms.
[14:44:21.453] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:21.454] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 839285
[14:44:21.454] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:44:21.454] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:44:21.518] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:44:22.183] INFO: Expecting 41600 events.
[14:44:25.925] INFO: 41600 events read in total (3215ms).
[14:44:25.930] INFO: Test took 4412ms.
[14:44:26.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:26.070] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 838955
[14:44:26.070] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:44:26.070] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:44:26.137] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:44:26.804] INFO: Expecting 41600 events.
[14:44:30.542] INFO: 41600 events read in total (3212ms).
[14:44:30.547] INFO: Test took 4410ms.
[14:44:30.684] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:30.684] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 839398
[14:44:30.685] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:44:30.685] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:44:30.749] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:44:31.414] INFO: Expecting 41600 events.
[14:44:35.161] INFO: 41600 events read in total (3220ms).
[14:44:35.167] INFO: Test took 4418ms.
[14:44:35.304] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:35.304] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 840801
[14:44:35.304] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:44:35.304] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:44:35.369] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:44:36.034] INFO: Expecting 41600 events.
[14:44:39.807] INFO: 41600 events read in total (3246ms).
[14:44:39.812] INFO: Test took 4443ms.
[14:44:39.950] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:39.950] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 841086
[14:44:39.950] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:44:39.950] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:44:40.014] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:44:40.680] INFO: Expecting 41600 events.
[14:44:44.445] INFO: 41600 events read in total (3239ms).
[14:44:44.450] INFO: Test took 4435ms.
[14:44:44.588] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:44.588] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 839399
[14:44:44.588] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:44:44.588] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:44:44.656] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:44:45.323] INFO: Expecting 41600 events.
[14:44:49.076] INFO: 41600 events read in total (3226ms).
[14:44:49.082] INFO: Test took 4426ms.
[14:44:49.227] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:49.227] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 838302
[14:44:49.227] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:44:49.228] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:44:49.294] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:44:49.960] INFO: Expecting 41600 events.
[14:44:53.808] INFO: 41600 events read in total (3322ms).
[14:44:53.814] INFO: Test took 4520ms.
[14:44:53.952] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:53.952] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 840013
[14:44:53.952] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:44:53.952] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:44:54.016] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:44:54.681] INFO: Expecting 41600 events.
[14:44:58.569] INFO: 41600 events read in total (3361ms).
[14:44:58.575] INFO: Test took 4559ms.
[14:44:58.718] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:58.718] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 841229
[14:44:58.718] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:44:58.718] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:44:58.782] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:44:59.447] INFO: Expecting 41600 events.
[14:45:03.243] INFO: 41600 events read in total (3269ms).
[14:45:03.248] INFO: Test took 4466ms.
[14:45:03.385] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:03.385] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 839893
[14:45:03.385] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:45:03.386] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:45:03.450] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:45:04.115] INFO: Expecting 41600 events.
[14:45:07.905] INFO: 41600 events read in total (3263ms).
[14:45:07.910] INFO: Test took 4460ms.
[14:45:08.048] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:08.049] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 839572
[14:45:08.049] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:45:08.049] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:45:08.114] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:45:08.780] INFO: Expecting 41600 events.
[14:45:12.545] INFO: 41600 events read in total (3239ms).
[14:45:12.551] INFO: Test took 4437ms.
[14:45:12.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:12.691] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 839613
[14:45:12.691] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:45:12.691] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:45:12.756] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:45:13.422] INFO: Expecting 41600 events.
[14:45:17.207] INFO: 41600 events read in total (3259ms).
[14:45:17.212] INFO: Test took 4456ms.
[14:45:17.349] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:17.349] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 839609
[14:45:17.349] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:45:17.350] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:45:17.416] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:45:18.085] INFO: Expecting 41600 events.
[14:45:21.846] INFO: 41600 events read in total (3234ms).
[14:45:21.851] INFO: Test took 4435ms.
[14:45:21.993] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:21.993] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 839152
[14:45:21.993] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:45:21.993] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:45:22.057] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:45:22.723] INFO: Expecting 41600 events.
[14:45:26.532] INFO: 41600 events read in total (3282ms).
[14:45:26.538] INFO: Test took 4481ms.
[14:45:26.677] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:26.677] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 839762
[14:45:26.677] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:45:26.677] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:45:26.743] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:45:27.411] INFO: Expecting 41600 events.
[14:45:31.190] INFO: 41600 events read in total (3252ms).
[14:45:31.195] INFO: Test took 4452ms.
[14:45:31.333] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:31.333] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 838018
[14:45:31.333] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:45:31.333] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:45:31.398] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:45:32.063] INFO: Expecting 41600 events.
[14:45:35.826] INFO: 41600 events read in total (3236ms).
[14:45:35.831] INFO: Test took 4433ms.
[14:45:35.971] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:35.971] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 836635
[14:45:35.971] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:45:35.972] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:45:36.034] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:45:36.699] INFO: Expecting 41600 events.
[14:45:40.246] INFO: 41600 events read in total (3020ms).
[14:45:40.251] INFO: Test took 4217ms.
[14:45:40.395] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:40.395] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 827094
[14:45:40.395] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:45:40.395] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:45:40.688] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 161 eff = 0.998918
[14:45:40.688] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 182 eff = 0.999159
[14:45:40.688] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 174 eff = 0.997692
[14:45:40.689] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 155 eff = 0.996082
[14:45:40.689] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 153 eff = 0.996154
[14:45:40.689] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 161 eff = 0.995769
[14:45:40.689] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 164 eff = 0.995793
[14:45:40.690] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 160 eff = 0.996514
[14:45:40.690] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 159 eff = 0.995192
[14:45:40.690] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 151 eff = 0.995649
[14:45:40.690] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 152 eff = 0.995505
[14:45:40.690] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 159 eff = 0.995793
[14:45:40.691] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 159 eff = 0.996106
[14:45:40.691] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 167 eff = 0.997933
[14:45:40.691] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 170 eff = 0.999038
[14:45:40.691] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 161 eff = 0.999111
[14:47:52.356] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[14:47:52.356] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:47:52.356] INFO: ----------------------------------------------------------------------
[14:47:52.356] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:47:52.356] INFO: ----------------------------------------------------------------------
[14:47:52.356] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:47:52.356] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:47:52.356] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:47:52.356] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:47:52.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:47:52.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:47:52.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:47:52.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:47:52.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:47:52.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:47:52.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:47:52.357] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:47:52.374] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:53.041] INFO: Expecting 208000 events.
[14:48:08.602] INFO: 208000 events read in total (15034ms).
[14:48:08.622] INFO: Test took 16248ms.
[14:48:09.433] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:09.433] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3876662
[14:48:09.433] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:48:09.433] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:09.933] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:48:09.933] INFO: number of red-efficiency pixels: 235 192 470 768 795 768 778 694 804 771 814 714 713 427 207 169
[14:48:09.933] INFO: number of X-ray hits detected: 140621 118463 194620 286941 300786 296821 295386 275216 305543 302633 300603 282363 284915 196761 117312 111118
[14:48:09.933] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:48:09.933] INFO: number of Vcal hits detected: 207749 207800 207453 207114 207075 207089 206922 207150 207064 207103 207051 207170 207191 207488 207758 207825
[14:48:09.933] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.6 99.6 99.5 99.6 99.6 99.6 99.6 99.6 99.6 99.8 99.9 99.9
[14:48:09.933] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.6 99.6 99.6 99.5 99.6 99.6 99.6 99.5 99.6 99.6 99.8 99.9 99.9
[14:48:09.933] INFO: X-ray hit rate [MHz/cm2]: 41.2 34.7 57.0 84.1 88.2 87.0 86.6 80.7 89.6 88.7 88.1 82.8 83.5 57.7 34.4 32.6
[14:48:09.933] INFO: PixTestHighRate::doXPixelAlive() done
[14:48:09.986] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:48:09.986] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:48:09.986] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:48:09.986] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:48:09.986] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:48:09.986] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:48:09.986] INFO: PixTest:: pg_setup set to default.
[14:48:36.388] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[14:48:36.389] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 377.8mA
[14:48:36.390] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[14:48:36.390] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.1 C
[14:48:36.390] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[14:48:36.390] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[14:48:36.390] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[14:48:36.390] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[14:48:36.390] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[14:48:36.390] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[14:48:36.390] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[14:48:36.390] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[14:48:36.390] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[14:48:36.390] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:48:36.469] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[14:48:36.469] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:48:36.479] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[14:48:36.479] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[14:48:36.479] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[14:48:36.479] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[14:48:36.479] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[14:48:36.479] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:48:36.482] QUIET: Connection to board 58 closed.
[14:48:37.398] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries