[16:01:57.373] INFO: *** Welcome to pxar ***
[16:01:57.373] INFO: *** Today: 2016/06/20
[16:01:57.397] INFO: *** Version: v1.9.0-814-g7497
[16:01:57.397] INFO: readRocDacs: data/mp348/dacParameters35_C0.dat .. data/mp348/dacParameters35_C15.dat
[16:01:57.398] INFO: readTbmDacs: data/mp348/tbmParameters_C0a.dat .. data/mp348/tbmParameters_C0b.dat
[16:01:57.398] INFO: readMaskFile: data/mp348/defaultMaskFile.dat
[16:01:57.398] INFO: readTrimFile: data/mp348/trimParameters35_C0.dat .. data/mp348/trimParameters35_C15.dat
[16:01:57.549] INFO: clk: 4
[16:01:57.549] INFO: ctr: 4
[16:01:57.549] INFO: sda: 19
[16:01:57.549] INFO: tin: 9
[16:01:57.549] INFO: level: 15
[16:01:57.549] INFO: triggerdelay: 0
[16:01:57.549] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:01:57.549] INFO: Log level: DEBUG
[16:01:57.560] QUIET: Connection to board DTB_WRPRHI opened.
[16:01:57.563] 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:
------------------------------------------------------
[16:01:57.566] INFO: RPC call hashes of host and DTB match: 398089610
[16:01:59.169] INFO: DUT info:
[16:01:59.169] INFO: The DUT currently contains the following objects:
[16:01:59.169] INFO: 2 TBM Cores tbm08c (2 ON)
[16:01:59.169] INFO: TBM Core alpha (0): 7 registers set
[16:01:59.169] INFO: TBM Core beta (1): 7 registers set
[16:01:59.169] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:01:59.169] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.169] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.170] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.170] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:01:59.170] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:01:59.171] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:01:59.172] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:01:59.173] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:01:59.175] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33259520
[16:01:59.175] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x10f7110
[16:01:59.175] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1085360
[16:01:59.175] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f7cb5d94010
[16:01:59.175] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f7cbbfff510
[16:01:59.175] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33267712 fPxarMemory = 0x7f7cb5d94010
[16:01:59.177] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[16:01:59.178] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 479.1mA
[16:01:59.178] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:01:59.675] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x16cde10
[16:01:59.737] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:01:59.738] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:01:59.738] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:01:59.743] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:01:59.743] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:01:59.743] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:01:59.752] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:01:59.752] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:01:59.769] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:01:59.769] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:01:59.784] INFO: PixTestCmd::init()
[16:01:59.791] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:01:59.791] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:01:59.791] INFO: readGainPedestalParameters data/mp348/phCalibrationFitErr35_C0.dat .. data/mp348/phCalibrationFitErr35_C15.dat
[16:02:00.035] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:02:00.035] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:02:00.048] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:02:00.055] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:02:00.055] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:02:00.055] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:02:00.076] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:02:00.076] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:02:00.076] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:02:00.076] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:02:00.076] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:02:00.081] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:02:00.081] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:02:00.094] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:02:00.094] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:02:00.094] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:02:00.094] INFO: readReadbackCal: data/mp348/readbackCal_C0.dat .. data/mp348/readbackCal_C15.dat
[16:02:00.106] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:02:00.115] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:02:00.115] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:02:00.115] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:02:00.115] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:02:00.131] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:02:00.131] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:02:05.438] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 02ma
[16:02:07.006] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:02:08.923] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:02:08.923] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:02:08.923] INFO: ----------------------------------------------------------------------
[16:02:08.923] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:02:08.923] INFO: ----------------------------------------------------------------------
[16:02:09.896] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:02:23.225] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[16:02:23.229] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:02:51.066] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1313256 events.
[16:02:56.138] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1313256, pixels seen in all events: 4492896
[16:02:56.236] INFO: Resuming triggers.
[16:03:09.568] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[16:03:09.601] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:03:37.311] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1313448 events.
[16:03:42.328] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1313448, pixels seen in all events: 4489350
[16:03:42.478] INFO: Resuming triggers.
[16:03:55.807] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[16:03:55.893] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:04:23.631] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1313154 events.
[16:04:28.707] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1313154, pixels seen in all events: 4492909
[16:04:28.863] INFO: Resuming triggers.
[16:04:42.190] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[16:04:42.226] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:05:09.834] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1312932 events.
[16:05:14.852] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1312932, pixels seen in all events: 4495936
[16:05:15.055] INFO: Resuming triggers.
[16:05:21.817] INFO: data taking finished, elapsed time: 60 seconds.
[16:05:22.013] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:05:36.250] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 666155 events.
[16:05:38.806] DEBUG: <PixTestXray.cc/processData:L823> # events read: 666155, pixels seen in all events: 2281130
[16:05:38.901] INFO: PixTest:: pg_setup set to default.
[16:05:38.938] INFO: PixTestXray::doPhRun() done
[16:05:46.259] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:05:46.260] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 390.7mA
[16:05:46.260] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 479.1mA
[16:05:46.260] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[16:05:46.260] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:05:46.260] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:05:46.261] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:05:46.261] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:05:46.261] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:05:46.261] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:05:46.261] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:05:46.261] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:05:46.261] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:05:46.261] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:05:46.261] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:05:46.261] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:05:46.261] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:05:46.261] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:05:46.261] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:05:46.261] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:05:46.261] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:05:46.262] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:05:46.532] QUIET: Connection to board 58 closed.
[16:05:46.611] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[16:05:52.299] INFO: *** Welcome to pxar ***
[16:05:52.299] INFO: *** Today: 2016/06/20
[16:05:52.325] INFO: *** Version: v1.9.0-814-g7497
[16:05:52.325] INFO: readRocDacs: data/mp348/dacParameters35_C0.dat .. data/mp348/dacParameters35_C15.dat
[16:05:52.326] INFO: readTbmDacs: data/mp348/tbmParameters_C0a.dat .. data/mp348/tbmParameters_C0b.dat
[16:05:52.327] INFO: readMaskFile: data/mp348/defaultMaskFile.dat
[16:05:52.327] INFO: readTrimFile: data/mp348/trimParameters35_C0.dat .. data/mp348/trimParameters35_C15.dat
[16:05:52.479] INFO: clk: 4
[16:05:52.479] INFO: ctr: 4
[16:05:52.479] INFO: sda: 19
[16:05:52.479] INFO: tin: 9
[16:05:52.479] INFO: level: 15
[16:05:52.479] INFO: triggerdelay: 0
[16:05:52.479] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[16:05:52.479] INFO: Log level: DEBUG
[16:05:52.490] QUIET: Connection to board DTB_WRPRHI opened.
[16:05:52.493] 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:
------------------------------------------------------
[16:05:52.496] INFO: RPC call hashes of host and DTB match: 398089610
[16:05:54.098] INFO: DUT info:
[16:05:54.098] INFO: The DUT currently contains the following objects:
[16:05:54.098] INFO: 2 TBM Cores tbm08c (2 ON)
[16:05:54.098] INFO: TBM Core alpha (0): 7 registers set
[16:05:54.098] INFO: TBM Core beta (1): 7 registers set
[16:05:54.098] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:05:54.098] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.098] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.098] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.098] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.098] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.098] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.098] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.098] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.098] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.098] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.099] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.099] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.099] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.099] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.099] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.099] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:05:54.099] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:05:54.100] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:05:54.101] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:05:54.102] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:05:54.104] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31461376
[16:05:54.104] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xbab110
[16:05:54.104] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xb39360
[16:05:54.104] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f4049d94010
[16:05:54.104] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f404ffff510
[16:05:54.104] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31469568 fPxarMemory = 0x7f4049d94010
[16:05:54.105] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.9mA
[16:05:54.107] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 479.1mA
[16:05:54.107] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.1 C
[16:05:54.603] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1181d50
[16:05:54.665] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:05:54.665] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:05:54.665] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:05:54.670] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:05:54.670] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:05:54.670] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:05:54.678] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:05:54.678] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:05:54.686] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:05:54.686] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:05:54.711] INFO: PixTestCmd::init()
[16:05:54.719] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:05:54.719] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:05:54.719] INFO: readGainPedestalParameters data/mp348/phCalibrationFitErr35_C0.dat .. data/mp348/phCalibrationFitErr35_C15.dat
[16:05:54.972] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:05:54.972] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:05:54.985] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:05:54.991] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:05:54.992] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:05:54.992] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:05:55.012] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:05:55.012] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:05:55.012] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:05:55.012] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:05:55.012] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:05:55.019] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:05:55.019] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:05:55.035] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:05:55.035] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:05:55.035] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:05:55.035] INFO: readReadbackCal: data/mp348/readbackCal_C0.dat .. data/mp348/readbackCal_C15.dat
[16:05:55.046] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:05:55.055] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[16:05:55.055] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[16:05:55.055] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[16:05:55.055] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[16:05:55.074] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:05:55.074] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:06:01.182] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 1 -> source to value 06ma
[16:06:02.974] DEBUG: <PixTab.cc/setParameter:L391> xxxPressed(): ID = 2 -> runseconds to value 60
[16:06:04.563] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): phrun
[16:06:04.563] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[16:06:04.563] INFO: ----------------------------------------------------------------------
[16:06:04.563] INFO: PixTestXray::doPhRun() fParRunSeconds = 60
[16:06:04.563] INFO: ----------------------------------------------------------------------
[16:06:05.536] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 60 seconds, fEventsMax = 6000000
[16:06:13.637] INFO: run duration 8 seconds, buffer almost full (81%), pausing triggers.
[16:06:13.640] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:06:38.888] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 798115 events.
[16:06:50.320] DEBUG: <PixTestXray.cc/processData:L823> # events read: 798115, pixels seen in all events: 10675654
[16:06:50.584] INFO: Resuming triggers.
[16:06:58.685] INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[16:06:58.715] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:07:23.954] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 798036 events.
[16:07:35.400] DEBUG: <PixTestXray.cc/processData:L823> # events read: 798036, pixels seen in all events: 10674702
[16:07:35.607] INFO: Resuming triggers.
[16:07:43.710] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[16:07:43.749] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:08:08.982] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 798321 events.
[16:08:20.387] DEBUG: <PixTestXray.cc/processData:L823> # events read: 798321, pixels seen in all events: 10672000
[16:08:20.582] INFO: Resuming triggers.
[16:08:28.679] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[16:08:28.756] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:08:54.032] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 797665 events.
[16:09:05.436] DEBUG: <PixTestXray.cc/processData:L823> # events read: 797665, pixels seen in all events: 10679265
[16:09:05.612] INFO: Resuming triggers.
[16:09:13.710] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[16:09:13.750] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer









[16:09:39.036] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 797768 events.
[16:09:50.512] DEBUG: <PixTestXray.cc/processData:L823> # events read: 797768, pixels seen in all events: 10677947
[16:09:50.675] INFO: Resuming triggers.
[16:09:58.777] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[16:09:58.810] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:10:24.192] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 798199 events.
[16:10:35.610] DEBUG: <PixTestXray.cc/processData:L823> # events read: 798199, pixels seen in all events: 10672891
[16:10:35.778] INFO: Resuming triggers.
[16:10:43.879] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[16:10:43.916] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:11:09.183] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 797990 events.
[16:11:20.577] DEBUG: <PixTestXray.cc/processData:L823> # events read: 797990, pixels seen in all events: 10676080
[16:11:20.750] INFO: Resuming triggers.
[16:11:24.127] INFO: data taking finished, elapsed time: 60 seconds.
[16:11:24.323] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[16:11:34.995] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 332565 events.
[16:11:39.752] DEBUG: <PixTestXray.cc/processData:L823> # events read: 332565, pixels seen in all events: 4448268
[16:11:39.849] INFO: PixTest:: pg_setup set to default.
[16:11:39.890] INFO: PixTestXray::doPhRun() done
[16:11:44.395] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:11:44.396] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 390.7mA
[16:11:44.396] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 479.9mA
[16:11:44.396] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:11:44.397] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:11:44.397] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:11:44.397] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:11:44.397] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:11:44.397] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:11:44.397] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:11:44.397] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:11:44.397] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:11:44.397] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:11:44.397] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:11:44.397] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:11:44.397] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:11:44.397] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:11:44.398] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:11:44.398] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:11:44.398] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[16:11:44.398] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:11:44.398] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:11:44.703] QUIET: Connection to board 58 closed.
[16:11:44.783] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[15:57:14.448] INFO: *** Welcome to pxar ***
[15:57:14.448] INFO: *** Today: 2016/06/20
[15:57:14.471] INFO: *** Version: v1.9.0-814-g7497
[15:57:14.471] INFO: readRocDacs: data/mp348/dacParameters35_C0.dat .. data/mp348/dacParameters35_C15.dat
[15:57:14.472] INFO: readTbmDacs: data/mp348/tbmParameters_C0a.dat .. data/mp348/tbmParameters_C0b.dat
[15:57:14.472] INFO: readMaskFile: data/mp348/defaultMaskFile.dat
[15:57:14.472] INFO: readTrimFile: data/mp348/trimParameters35_C0.dat .. data/mp348/trimParameters35_C15.dat
[15:57:14.621] INFO: clk: 4
[15:57:14.621] INFO: ctr: 4
[15:57:14.621] INFO: sda: 19
[15:57:14.621] INFO: tin: 9
[15:57:14.621] INFO: level: 15
[15:57:14.621] INFO: triggerdelay: 0
[15:57:14.621] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:57:14.621] INFO: Log level: DEBUG
[15:57:14.632] QUIET: Connection to board DTB_WRPRHI opened.
[15:57:14.635] 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:57:14.638] INFO: RPC call hashes of host and DTB match: 398089610
[15:57:16.240] INFO: DUT info:
[15:57:16.240] INFO: The DUT currently contains the following objects:
[15:57:16.240] INFO: 2 TBM Cores tbm08c (2 ON)
[15:57:16.240] INFO: TBM Core alpha (0): 7 registers set
[15:57:16.240] INFO: TBM Core beta (1): 7 registers set
[15:57:16.240] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:57:16.240] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.240] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:57:16.241] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:57:16.242] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:57:16.243] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:57:16.244] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:57:16.246] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32755712
[15:57:16.246] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x18ba110
[15:57:16.246] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1848360
[15:57:16.246] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f1729d94010
[15:57:16.246] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f172ffff510
[15:57:16.246] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32763904 fPxarMemory = 0x7f1729d94010
[15:57:16.247] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[15:57:16.249] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 479.1mA
[15:57:16.249] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: 0.2 C
[15:57:16.745] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1e90e20
[15:57:16.807] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:57:16.807] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:57:16.807] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:57:16.812] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:57:16.812] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:57:16.812] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:57:16.821] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:57:16.821] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:57:16.827] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:57:16.828] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:57:16.847] INFO: PixTestCmd::init()
[15:57:16.858] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:57:16.858] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:57:16.858] INFO: readGainPedestalParameters data/mp348/phCalibrationFitErr35_C0.dat .. data/mp348/phCalibrationFitErr35_C15.dat
[15:57:17.103] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:57:17.103] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:57:17.115] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:57:17.122] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:57:17.122] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:57:17.122] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:57:17.142] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:57:17.142] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:57:17.142] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:57:17.142] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:57:17.142] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:57:17.149] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:57:17.149] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:57:17.165] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:57:17.165] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:57:17.165] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:57:17.165] INFO: readReadbackCal: data/mp348/readbackCal_C0.dat .. data/mp348/readbackCal_C15.dat
[15:57:17.176] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:57:17.185] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:57:17.185] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:57:17.185] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:57:17.185] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:57:17.201] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:57:17.201] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:57:20.350] INFO: ######################################################################
[15:57:20.350] INFO: PixTestAlive::doTest()
[15:57:20.350] INFO: ######################################################################
[15:57:20.353] INFO: ----------------------------------------------------------------------
[15:57:20.353] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:57:20.353] INFO: ----------------------------------------------------------------------
[15:57:20.356] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:57:20.737] INFO: Expecting 41600 events.
[15:57:25.111] INFO: 41600 events read in total (3657ms).
[15:57:25.259] INFO: Test took 4903ms.
[15:57:25.269] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:25.269] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[15:57:25.269] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:57:25.542] INFO: PixTestAlive::aliveTest() done
[15:57:25.542] INFO: number of dead pixels (per ROC): 0 0 4 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:57:25.542] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 19 14 13 37 36 41 31 36 43 43 26 32 30 26 15 12
[15:57:25.568] INFO: ----------------------------------------------------------------------
[15:57:25.568] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:57:25.568] INFO: ----------------------------------------------------------------------
[15:57:25.570] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:57:25.947] INFO: Expecting 41600 events.
[15:57:29.070] INFO: 41600 events read in total (2407ms).
[15:57:29.071] INFO: Test took 3501ms.
[15:57:29.071] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:29.071] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:57:29.071] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:57:29.071] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:57:29.493] INFO: PixTestAlive::maskTest() done
[15:57:29.493] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:57:29.516] INFO: ----------------------------------------------------------------------
[15:57:29.516] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:57:29.516] INFO: ----------------------------------------------------------------------
[15:57:29.518] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:57:29.896] INFO: Expecting 41600 events.
[15:57:34.271] INFO: 41600 events read in total (3660ms).
[15:57:34.272] INFO: Test took 4754ms.
[15:57:34.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:34.283] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[15:57:34.284] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:57:34.654] INFO: PixTestAlive::addressDecodingTest() done
[15:57:34.655] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:57:34.655] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:57:37.051] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:57:37.051] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:57:37.051] INFO: ----------------------------------------------------------------------
[15:57:37.051] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:57:37.051] INFO: ----------------------------------------------------------------------
[15:57:37.190] INFO: Expecting 768 events.
[15:57:38.324] INFO: 768 events read in total (419ms).
[15:57:38.324] INFO: Test took 1268ms.
[15:57:38.330] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:57:39.128] INFO: Expecting 41600 events.
[15:57:42.440] INFO: 41600 events read in total (2786ms).
[15:57:42.444] INFO: Test took 4114ms.
[15:57:42.519] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:42.519] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 426917
[15:57:42.519] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:57:42.519] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:57:42.554] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:57:43.219] INFO: Expecting 41600 events.
[15:57:46.646] INFO: 41600 events read in total (2900ms).
[15:57:46.650] INFO: Test took 4096ms.
[15:57:46.722] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:46.722] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 435413
[15:57:46.722] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:57:46.723] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:57:46.759] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:57:47.424] INFO: Expecting 41600 events.
[15:57:50.882] INFO: 41600 events read in total (2931ms).
[15:57:50.886] INFO: Test took 4127ms.
[15:57:50.959] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:50.959] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 438571
[15:57:50.959] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:57:50.959] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:57:50.997] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:57:51.662] INFO: Expecting 41600 events.
[15:57:55.119] INFO: 41600 events read in total (2931ms).
[15:57:55.122] INFO: Test took 4125ms.
[15:57:55.194] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:55.194] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 435922
[15:57:55.194] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:57:55.195] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:57:55.231] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:57:55.896] INFO: Expecting 41600 events.
[15:57:59.363] INFO: 41600 events read in total (2940ms).
[15:57:59.367] INFO: Test took 4136ms.
[15:57:59.438] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:59.438] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 437491
[15:57:59.438] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:57:59.439] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:57:59.476] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:00.141] INFO: Expecting 41600 events.
[15:58:03.611] INFO: 41600 events read in total (2943ms).
[15:58:03.615] INFO: Test took 4139ms.
[15:58:03.686] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:03.686] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 436982
[15:58:03.686] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:58:03.687] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:03.723] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:04.388] INFO: Expecting 41600 events.
[15:58:07.869] INFO: 41600 events read in total (2954ms).
[15:58:07.873] INFO: Test took 4150ms.
[15:58:07.944] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:07.944] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 437513
[15:58:07.944] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:58:07.945] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:07.981] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:08.646] INFO: Expecting 41600 events.
[15:58:12.124] INFO: 41600 events read in total (2951ms).
[15:58:12.127] INFO: Test took 4146ms.
[15:58:12.198] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:12.198] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 435842
[15:58:12.199] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:58:12.199] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:12.236] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:12.901] INFO: Expecting 41600 events.
[15:58:16.380] INFO: 41600 events read in total (2952ms).
[15:58:16.384] INFO: Test took 4148ms.
[15:58:16.456] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:16.456] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 437060
[15:58:16.456] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:58:16.456] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:16.493] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:17.158] INFO: Expecting 41600 events.
[15:58:20.629] INFO: 41600 events read in total (2944ms).
[15:58:20.632] INFO: Test took 4139ms.
[15:58:20.704] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:20.704] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 437121
[15:58:20.704] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:58:20.704] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:20.741] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:21.406] INFO: Expecting 41600 events.
[15:58:24.879] INFO: 41600 events read in total (2946ms).
[15:58:24.883] INFO: Test took 4141ms.
[15:58:24.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:24.954] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 437338
[15:58:24.954] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:58:24.954] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:24.992] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:25.657] INFO: Expecting 41600 events.
[15:58:29.136] INFO: 41600 events read in total (2953ms).
[15:58:29.139] INFO: Test took 4147ms.
[15:58:29.212] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:29.212] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 437364
[15:58:29.212] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:58:29.212] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:29.250] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:29.914] INFO: Expecting 41600 events.
[15:58:33.384] INFO: 41600 events read in total (2943ms).
[15:58:33.387] INFO: Test took 4137ms.
[15:58:33.459] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:33.459] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 436297
[15:58:33.459] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:58:33.459] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:33.497] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:34.162] INFO: Expecting 41600 events.
[15:58:37.639] INFO: 41600 events read in total (2951ms).
[15:58:37.642] INFO: Test took 4145ms.
[15:58:37.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:37.714] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 437306
[15:58:37.714] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:58:37.714] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:37.751] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:38.416] INFO: Expecting 41600 events.
[15:58:41.889] INFO: 41600 events read in total (2946ms).
[15:58:41.893] INFO: Test took 4142ms.
[15:58:41.966] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:41.966] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 437646
[15:58:41.966] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:58:41.966] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:42.003] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:42.668] INFO: Expecting 41600 events.
[15:58:46.145] INFO: 41600 events read in total (2951ms).
[15:58:46.149] INFO: Test took 4146ms.
[15:58:46.222] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:46.222] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 438156
[15:58:46.222] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:58:46.222] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:46.259] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:46.924] INFO: Expecting 41600 events.
[15:58:50.406] INFO: 41600 events read in total (2955ms).
[15:58:50.409] INFO: Test took 4149ms.
[15:58:50.481] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:50.481] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 436946
[15:58:50.481] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:58:50.482] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:50.519] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:51.184] INFO: Expecting 41600 events.
[15:58:54.654] INFO: 41600 events read in total (2944ms).
[15:58:54.658] INFO: Test took 4139ms.
[15:58:54.729] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:54.729] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 436456
[15:58:54.729] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:58:54.730] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:54.767] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:55.432] INFO: Expecting 41600 events.
[15:58:58.885] INFO: 41600 events read in total (2926ms).
[15:58:58.888] INFO: Test took 4121ms.
[15:58:58.959] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:58.959] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 437040
[15:58:58.959] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:58:58.960] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:58:58.997] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:58:59.662] INFO: Expecting 41600 events.
[15:59:02.970] INFO: 41600 events read in total (2781ms).
[15:59:02.974] INFO: Test took 3977ms.
[15:59:03.045] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:03.045] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 429628
[15:59:03.045] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:59:03.045] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:59:03.303] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 156 eff = 0.999832
[15:59:03.303] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 174 eff = 0.999856
[15:59:03.303] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 145 eff = 0.998678
[15:59:03.303] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 160 eff = 0.999159
[15:59:03.304] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 149 eff = 0.99899
[15:59:03.304] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 150 eff = 0.999063
[15:59:03.304] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 147 eff = 0.999183
[15:59:03.304] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 188 eff = 0.999111
[15:59:03.304] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 180 eff = 0.999038
[15:59:03.304] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 194 eff = 0.999014
[15:59:03.304] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 186 eff = 0.999207
[15:59:03.305] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 182 eff = 0.999327
[15:59:03.305] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 171 eff = 0.999327
[15:59:03.305] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 184 eff = 0.999543
[15:59:03.305] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 118 eff = 0.99976
[15:59:03.305] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 143 eff = 0.99976
[15:59:07.419] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:59:07.419] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:59:07.419] INFO: ----------------------------------------------------------------------
[15:59:07.419] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:59:07.419] INFO: ----------------------------------------------------------------------
[15:59:07.419] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:59:07.419] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:59:07.419] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:59:07.419] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:59:07.419] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:59:07.419] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:59:07.419] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:59:07.419] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:59:07.419] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:59:07.419] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:59:07.419] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:59:07.420] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:59:07.438] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:59:08.107] INFO: Expecting 208000 events.
[15:59:21.357] INFO: 208000 events read in total (12724ms).
[15:59:21.368] INFO: Test took 13930ms.
[15:59:21.694] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:21.694] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1904782
[15:59:21.694] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:59:21.694] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:59:22.055] INFO: number of dead pixels (per ROC): 0 0 4 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:59:22.055] INFO: number of red-efficiency pixels: 71 70 126 236 205 230 220 250 194 233 198 175 190 115 73 79
[15:59:22.055] INFO: number of X-ray hits detected: 61546 53213 89352 136297 143574 145346 146684 141355 152565 151169 146629 135801 136168 89475 51154 57898
[15:59:22.055] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:59:22.055] INFO: number of Vcal hits detected: 207926 207929 207673 207757 207787 207726 207768 207704 207802 207756 207792 207815 207803 207882 207925 207921
[15:59:22.055] 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
[15:59:22.055] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:59:22.055] INFO: X-ray hit rate [MHz/cm2]: 18.0 15.6 26.2 39.9 42.1 42.6 43.0 41.4 44.7 44.3 43.0 39.8 39.9 26.2 15.0 17.0
[15:59:22.055] INFO: PixTestHighRate::doXPixelAlive() done
[15:59:22.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:59:22.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:59:22.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:59:22.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:59:22.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:59:22.100] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:59:22.100] INFO: PixTest:: pg_setup set to default.
[15:59:26.275] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:59:26.275] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[15:59:28.307] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:59:28.308] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 390.7mA
[15:59:28.308] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 479.1mA
[15:59:28.308] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[15:59:28.308] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:59:28.308] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:59:28.308] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:59:28.309] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:59:28.309] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:59:28.309] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:59:28.309] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:59:28.309] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:59:28.309] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:59:28.309] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:59:28.398] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:59:28.398] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:59:28.407] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:59:28.407] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:59:28.407] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:59:28.407] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:59:28.407] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:59:28.407] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:59:28.411] QUIET: Connection to board 58 closed.
[15:59:28.490] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[15:54:37.987] INFO: *** Welcome to pxar ***
[15:54:37.988] INFO: *** Today: 2016/06/20
[15:54:38.012] INFO: *** Version: v1.9.0-814-g7497
[15:54:38.012] INFO: readRocDacs: data/mp348/dacParameters35_C0.dat .. data/mp348/dacParameters35_C15.dat
[15:54:38.013] INFO: readTbmDacs: data/mp348/tbmParameters_C0a.dat .. data/mp348/tbmParameters_C0b.dat
[15:54:38.013] INFO: readMaskFile: data/mp348/defaultMaskFile.dat
[15:54:38.013] INFO: readTrimFile: data/mp348/trimParameters35_C0.dat .. data/mp348/trimParameters35_C15.dat
[15:54:38.162] INFO: clk: 4
[15:54:38.162] INFO: ctr: 4
[15:54:38.162] INFO: sda: 19
[15:54:38.162] INFO: tin: 9
[15:54:38.163] INFO: level: 15
[15:54:38.163] INFO: triggerdelay: 0
[15:54:38.163] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:54:38.163] INFO: Log level: DEBUG
[15:54:38.174] QUIET: Connection to board DTB_WRPRHI opened.
[15:54:38.177] 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:54:38.180] INFO: RPC call hashes of host and DTB match: 398089610
[15:54:39.784] INFO: DUT info:
[15:54:39.784] INFO: The DUT currently contains the following objects:
[15:54:39.784] INFO: 2 TBM Cores tbm08c (2 ON)
[15:54:39.784] INFO: TBM Core alpha (0): 7 registers set
[15:54:39.784] INFO: TBM Core beta (1): 7 registers set
[15:54:39.784] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:54:39.784] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.784] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:54:39.785] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:54:39.786] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:54:39.787] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:54:39.788] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:54:39.790] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35340288
[15:54:39.790] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xa40110
[15:54:39.790] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x9ce360
[15:54:39.790] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f46c1d94010
[15:54:39.790] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f46c7fff510
[15:54:39.790] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35348480 fPxarMemory = 0x7f46c1d94010
[15:54:39.791] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[15:54:39.793] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 479.1mA
[15:54:39.793] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[15:54:40.291] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1016d00
[15:54:40.353] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:54:40.354] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:54:40.354] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:54:40.359] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:54:40.359] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:54:40.359] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:54:40.368] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:54:40.368] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:54:40.379] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:54:40.379] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:54:40.392] INFO: PixTestCmd::init()
[15:54:40.402] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:54:40.402] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:54:40.402] INFO: readGainPedestalParameters data/mp348/phCalibrationFitErr35_C0.dat .. data/mp348/phCalibrationFitErr35_C15.dat
[15:54:40.647] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:54:40.647] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:54:40.660] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:54:40.666] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:54:40.666] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:54:40.666] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:54:40.687] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:54:40.687] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:54:40.687] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:54:40.687] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:54:40.687] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:54:40.694] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:54:40.694] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:54:40.710] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:54:40.710] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:54:40.711] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:54:40.711] INFO: readReadbackCal: data/mp348/readbackCal_C0.dat .. data/mp348/readbackCal_C15.dat
[15:54:40.720] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:54:40.728] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:54:40.728] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:54:40.728] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:54:40.728] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:54:40.740] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:54:40.740] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:54:57.558] INFO: ######################################################################
[15:54:57.558] INFO: PixTestAlive::doTest()
[15:54:57.558] INFO: ######################################################################
[15:54:57.561] INFO: ----------------------------------------------------------------------
[15:54:57.561] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:54:57.561] INFO: ----------------------------------------------------------------------
[15:54:57.564] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:54:57.944] INFO: Expecting 41600 events.
[15:55:02.316] INFO: 41600 events read in total (3654ms).
[15:55:02.463] INFO: Test took 4899ms.
[15:55:02.476] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:02.476] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[15:55:02.476] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:55:02.748] INFO: PixTestAlive::aliveTest() done
[15:55:02.748] INFO: number of dead pixels (per ROC): 0 0 4 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:55:02.748] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 18 25 34 51 48 55 61 55 40 61 46 31 54 26 28 25
[15:55:02.773] INFO: ----------------------------------------------------------------------
[15:55:02.773] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:55:02.773] INFO: ----------------------------------------------------------------------
[15:55:02.775] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:03.153] INFO: Expecting 41600 events.
[15:55:06.274] INFO: 41600 events read in total (2405ms).
[15:55:06.274] INFO: Test took 3499ms.
[15:55:06.274] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:06.274] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:55:06.274] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:55:06.275] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:55:06.697] INFO: PixTestAlive::maskTest() done
[15:55:06.697] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:55:06.719] INFO: ----------------------------------------------------------------------
[15:55:06.719] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:55:06.719] INFO: ----------------------------------------------------------------------
[15:55:06.721] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:07.101] INFO: Expecting 41600 events.
[15:55:11.498] INFO: 41600 events read in total (3681ms).
[15:55:11.500] INFO: Test took 4779ms.
[15:55:11.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:11.511] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[15:55:11.511] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:55:11.881] INFO: PixTestAlive::addressDecodingTest() done
[15:55:11.881] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:55:11.881] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:55:13.651] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:55:13.651] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:55:13.651] INFO: ----------------------------------------------------------------------
[15:55:13.651] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:55:13.651] INFO: ----------------------------------------------------------------------
[15:55:13.790] INFO: Expecting 768 events.
[15:55:14.924] INFO: 768 events read in total (419ms).
[15:55:14.924] INFO: Test took 1268ms.
[15:55:14.930] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:15.728] INFO: Expecting 41600 events.
[15:55:19.235] INFO: 41600 events read in total (2981ms).
[15:55:19.240] INFO: Test took 4310ms.
[15:55:19.350] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:19.350] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 622748
[15:55:19.351] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:55:19.351] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:19.398] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:20.063] INFO: Expecting 41600 events.
[15:55:23.713] INFO: 41600 events read in total (3123ms).
[15:55:23.717] INFO: Test took 4319ms.
[15:55:23.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:23.823] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 629969
[15:55:23.823] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:55:23.824] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:23.873] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:24.538] INFO: Expecting 41600 events.
[15:55:28.216] INFO: 41600 events read in total (3152ms).
[15:55:28.221] INFO: Test took 4348ms.
[15:55:28.327] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:28.327] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632013
[15:55:28.327] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:55:28.328] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:28.377] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:29.042] INFO: Expecting 41600 events.
[15:55:32.705] INFO: 41600 events read in total (3136ms).
[15:55:32.709] INFO: Test took 4332ms.
[15:55:32.817] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:32.817] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632536
[15:55:32.817] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:55:32.817] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:32.867] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:33.532] INFO: Expecting 41600 events.
[15:55:37.195] INFO: 41600 events read in total (3137ms).
[15:55:37.200] INFO: Test took 4333ms.
[15:55:37.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:37.306] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632710
[15:55:37.306] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:55:37.306] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:37.356] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:38.021] INFO: Expecting 41600 events.
[15:55:41.712] INFO: 41600 events read in total (3164ms).
[15:55:41.717] INFO: Test took 4361ms.
[15:55:41.825] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:41.825] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632998
[15:55:41.825] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:55:41.826] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:41.875] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:42.540] INFO: Expecting 41600 events.
[15:55:46.239] INFO: 41600 events read in total (3172ms).
[15:55:46.243] INFO: Test took 4368ms.
[15:55:46.350] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:46.350] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632084
[15:55:46.350] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:55:46.350] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:46.400] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:47.065] INFO: Expecting 41600 events.
[15:55:50.767] INFO: 41600 events read in total (3175ms).
[15:55:50.771] INFO: Test took 4371ms.
[15:55:50.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:50.878] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632580
[15:55:50.878] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:55:50.878] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:50.927] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:51.592] INFO: Expecting 41600 events.
[15:55:55.305] INFO: 41600 events read in total (3186ms).
[15:55:55.309] INFO: Test took 4382ms.
[15:55:55.418] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:55.419] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632887
[15:55:55.419] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:55:55.419] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:55.469] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:55:56.134] INFO: Expecting 41600 events.
[15:55:59.836] INFO: 41600 events read in total (3176ms).
[15:55:59.840] INFO: Test took 4371ms.
[15:55:59.948] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:59.948] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632569
[15:55:59.948] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:55:59.948] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:55:59.997] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:56:00.662] INFO: Expecting 41600 events.
[15:56:04.336] INFO: 41600 events read in total (3147ms).
[15:56:04.340] INFO: Test took 4343ms.
[15:56:04.446] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:04.446] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 633402
[15:56:04.446] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:56:04.446] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:56:04.496] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:56:05.161] INFO: Expecting 41600 events.
[15:56:08.840] INFO: 41600 events read in total (3153ms).
[15:56:08.845] INFO: Test took 4349ms.
[15:56:08.952] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:08.952] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632801
[15:56:08.952] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:56:08.952] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:56:09.002] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:56:09.667] INFO: Expecting 41600 events.
[15:56:13.378] INFO: 41600 events read in total (3184ms).
[15:56:13.383] INFO: Test took 4381ms.
[15:56:13.490] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:13.490] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 633267
[15:56:13.490] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:56:13.491] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:56:13.540] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:56:14.206] INFO: Expecting 41600 events.
[15:56:17.910] INFO: 41600 events read in total (3178ms).
[15:56:17.915] INFO: Test took 4375ms.
[15:56:18.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:18.022] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 631675
[15:56:18.022] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:56:18.023] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:56:18.072] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:56:18.738] INFO: Expecting 41600 events.
[15:56:22.450] INFO: 41600 events read in total (3185ms).
[15:56:22.455] INFO: Test took 4383ms.
[15:56:22.561] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:22.561] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 633020
[15:56:22.561] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:56:22.561] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:56:22.611] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:56:23.277] INFO: Expecting 41600 events.
[15:56:26.988] INFO: 41600 events read in total (3184ms).
[15:56:26.992] INFO: Test took 4381ms.
[15:56:27.100] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:27.100] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 631809
[15:56:27.100] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:56:27.100] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:56:27.150] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:56:27.815] INFO: Expecting 41600 events.
[15:56:31.517] INFO: 41600 events read in total (3175ms).
[15:56:31.521] INFO: Test took 4371ms.
[15:56:31.627] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:31.627] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632752
[15:56:31.627] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:56:31.627] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:56:31.677] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:56:32.342] INFO: Expecting 41600 events.
[15:56:36.020] INFO: 41600 events read in total (3151ms).
[15:56:36.025] INFO: Test took 4348ms.
[15:56:36.132] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:36.132] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632699
[15:56:36.132] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:56:36.132] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:56:36.182] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:56:36.847] INFO: Expecting 41600 events.
[15:56:40.520] INFO: 41600 events read in total (3147ms).
[15:56:40.525] INFO: Test took 4343ms.
[15:56:40.631] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:40.631] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 629590
[15:56:40.631] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:56:40.632] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:56:40.681] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:56:41.346] INFO: Expecting 41600 events.
[15:56:44.891] INFO: 41600 events read in total (3018ms).
[15:56:44.895] INFO: Test took 4214ms.
[15:56:44.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:44.998] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 625467
[15:56:44.998] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:56:44.999] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:56:45.269] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 157 eff = 0.999712
[15:56:45.269] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 183 eff = 0.999615
[15:56:45.269] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 157 eff = 0.997933
[15:56:45.270] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 179 eff = 0.998221
[15:56:45.270] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 154 eff = 0.997885
[15:56:45.270] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 147 eff = 0.998317
[15:56:45.270] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 147 eff = 0.997885
[15:56:45.270] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 182 eff = 0.998149
[15:56:45.270] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 177 eff = 0.998173
[15:56:45.270] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 194 eff = 0.998077
[15:56:45.271] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 189 eff = 0.998269
[15:56:45.271] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 188 eff = 0.998125
[15:56:45.271] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 168 eff = 0.998486
[15:56:45.271] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 182 eff = 0.999087
[15:56:45.271] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 126 eff = 0.999543
[15:56:45.271] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 158 eff = 0.999688
[15:56:47.803] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:56:47.803] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:56:47.803] INFO: ----------------------------------------------------------------------
[15:56:47.803] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:56:47.803] INFO: ----------------------------------------------------------------------
[15:56:47.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:56:47.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:56:47.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:56:47.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:56:47.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:56:47.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:56:47.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:56:47.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:56:47.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:56:47.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:56:47.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:56:47.803] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:56:47.821] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:56:48.489] INFO: Expecting 208000 events.
[15:57:03.044] INFO: 208000 events read in total (14028ms).
[15:57:03.059] INFO: Test took 15238ms.
[15:57:03.558] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:03.558] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2871977
[15:57:03.558] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:57:03.559] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:57:03.983] INFO: number of dead pixels (per ROC): 0 0 4 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:57:03.983] INFO: number of red-efficiency pixels: 113 114 219 309 415 403 373 403 401 437 343 359 383 219 116 106
[15:57:03.983] INFO: number of X-ray hits detected: 93889 80667 135716 209470 220439 222042 223619 215618 232835 230264 223594 207000 206172 137235 78829 88032
[15:57:03.983] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:57:03.983] INFO: number of Vcal hits detected: 207884 207882 207573 207667 207535 207567 207596 207564 207570 207522 207629 207612 207588 207766 207881 207892
[15:57:03.983] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[15:57:03.983] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.9 99.9
[15:57:03.983] INFO: X-ray hit rate [MHz/cm2]: 27.5 23.6 39.8 61.4 64.6 65.1 65.5 63.2 68.2 67.5 65.5 60.7 60.4 40.2 23.1 25.8
[15:57:03.983] INFO: PixTestHighRate::doXPixelAlive() done
[15:57:04.028] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:57:04.028] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:57:04.028] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:57:04.028] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:57:04.028] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:57:04.028] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:57:04.028] INFO: PixTest:: pg_setup set to default.
[15:57:07.059] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:57:07.059] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C11_V0 -> highRate_xraymap_mod
[15:57:10.019] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:57:10.019] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 389.9mA
[15:57:10.020] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 479.1mA
[15:57:10.020] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.5 C
[15:57:10.020] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:57:10.020] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:57:10.020] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:57:10.020] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:57:10.021] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:57:10.021] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:57:10.021] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:57:10.021] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:57:10.021] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:57:10.021] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:57:10.111] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:57:10.112] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:57:10.120] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:57:10.121] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:57:10.121] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:57:10.121] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:57:10.121] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:57:10.121] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:57:10.123] QUIET: Connection to board 58 closed.
[15:57:10.203] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[15:51:59.819] INFO: *** Welcome to pxar ***
[15:51:59.819] INFO: *** Today: 2016/06/20
[15:51:59.844] INFO: *** Version: v1.9.0-814-g7497
[15:51:59.844] INFO: readRocDacs: data/mp348/dacParameters35_C0.dat .. data/mp348/dacParameters35_C15.dat
[15:51:59.845] INFO: readTbmDacs: data/mp348/tbmParameters_C0a.dat .. data/mp348/tbmParameters_C0b.dat
[15:51:59.845] INFO: readMaskFile: data/mp348/defaultMaskFile.dat
[15:51:59.845] INFO: readTrimFile: data/mp348/trimParameters35_C0.dat .. data/mp348/trimParameters35_C15.dat
[15:51:59.997] INFO: clk: 4
[15:51:59.997] INFO: ctr: 4
[15:51:59.997] INFO: sda: 19
[15:51:59.997] INFO: tin: 9
[15:51:59.997] INFO: level: 15
[15:51:59.997] INFO: triggerdelay: 0
[15:51:59.997] QUIET: Instanciating API for pxar v1.9.0+803~gf2ecfa7
[15:51:59.997] INFO: Log level: DEBUG
[15:52:00.009] QUIET: Connection to board DTB_WRPRHI opened.
[15:52:00.012] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[15:52:00.015] INFO: RPC call hashes of host and DTB match: 398089610
[15:52:01.614] INFO: DUT info:
[15:52:01.615] INFO: The DUT currently contains the following objects:
[15:52:01.615] INFO: 2 TBM Cores tbm08c (2 ON)
[15:52:01.615] INFO: TBM Core alpha (0): 7 registers set
[15:52:01.615] INFO: TBM Core beta (1): 7 registers set
[15:52:01.615] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:52:01.615] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:52:01.615] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:52:01.616] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[15:52:01.617] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[15:52:01.618] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[15:52:01.619] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[15:52:01.619] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[15:52:01.619] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[15:52:01.619] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[15:52:01.619] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[15:52:01.619] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[15:52:01.621] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32923648
[15:52:01.621] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x27cc110
[15:52:01.621] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x275a360
[15:52:01.621] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f4eadd94010
[15:52:01.621] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f4eb3fff510
[15:52:01.621] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32931840 fPxarMemory = 0x7f4eadd94010
[15:52:01.622] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 389.1mA
[15:52:01.623] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 479.1mA
[15:52:01.623] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: 0.1 C
[15:52:02.120] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2da2e00
[15:52:02.181] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[15:52:02.181] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[15:52:02.181] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[15:52:02.187] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[15:52:02.187] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[15:52:02.187] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[15:52:02.196] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[15:52:02.196] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[15:52:02.203] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[15:52:02.203] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[15:52:02.226] INFO: PixTestCmd::init()
[15:52:02.232] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[15:52:02.232] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[15:52:02.232] INFO: readGainPedestalParameters data/mp348/phCalibrationFitErr35_C0.dat .. data/mp348/phCalibrationFitErr35_C15.dat
[15:52:02.475] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[15:52:02.475] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[15:52:02.488] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[15:52:02.496] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[15:52:02.496] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[15:52:02.496] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[15:52:02.517] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[15:52:02.517] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[15:52:02.517] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[15:52:02.517] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[15:52:02.517] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[15:52:02.523] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[15:52:02.523] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[15:52:02.540] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[15:52:02.540] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[15:52:02.540] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[15:52:02.540] INFO: readReadbackCal: data/mp348/readbackCal_C0.dat .. data/mp348/readbackCal_C15.dat
[15:52:02.549] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[15:52:02.556] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[15:52:02.556] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[15:52:02.557] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[15:52:02.557] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[15:52:02.568] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[15:52:02.568] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[15:52:07.358] INFO: ######################################################################
[15:52:07.358] INFO: PixTestAlive::doTest()
[15:52:07.358] INFO: ######################################################################
[15:52:07.361] INFO: ----------------------------------------------------------------------
[15:52:07.361] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:52:07.361] INFO: ----------------------------------------------------------------------
[15:52:07.363] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:07.743] INFO: Expecting 41600 events.
[15:52:12.149] INFO: 41600 events read in total (3688ms).
[15:52:12.295] INFO: Test took 4931ms.
[15:52:12.309] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:12.309] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[15:52:12.309] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[15:52:12.583] INFO: PixTestAlive::aliveTest() done
[15:52:12.583] INFO: number of dead pixels (per ROC): 0 0 4 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:52:12.584] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 29 22 43 72 63 69 71 92 70 69 66 78 66 49 27 21
[15:52:12.609] INFO: ----------------------------------------------------------------------
[15:52:12.609] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:52:12.609] INFO: ----------------------------------------------------------------------
[15:52:12.611] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:12.988] INFO: Expecting 41600 events.
[15:52:16.114] INFO: 41600 events read in total (2411ms).
[15:52:16.115] INFO: Test took 3504ms.
[15:52:16.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:16.115] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[15:52:16.115] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[15:52:16.115] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:52:16.538] INFO: PixTestAlive::maskTest() done
[15:52:16.538] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:52:16.559] INFO: ----------------------------------------------------------------------
[15:52:16.559] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:52:16.560] INFO: ----------------------------------------------------------------------
[15:52:16.562] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:16.938] INFO: Expecting 41600 events.
[15:52:21.325] INFO: 41600 events read in total (3671ms).
[15:52:21.326] INFO: Test took 4764ms.
[15:52:21.337] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:21.337] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66556
[15:52:21.337] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[15:52:21.707] INFO: PixTestAlive::addressDecodingTest() done
[15:52:21.707] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:52:21.707] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:52:23.867] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[15:52:23.867] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[15:52:23.867] INFO: ----------------------------------------------------------------------
[15:52:23.867] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:52:23.867] INFO: ----------------------------------------------------------------------
[15:52:24.008] INFO: Expecting 768 events.
[15:52:25.142] INFO: 768 events read in total (419ms).
[15:52:25.142] INFO: Test took 1267ms.
[15:52:25.148] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:25.946] INFO: Expecting 41600 events.
[15:52:29.676] INFO: 41600 events read in total (3204ms).
[15:52:29.681] INFO: Test took 4533ms.
[15:52:29.819] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:29.819] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 798768
[15:52:29.819] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[15:52:29.819] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:52:29.878] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:30.543] INFO: Expecting 41600 events.
[15:52:34.413] INFO: 41600 events read in total (3344ms).
[15:52:34.418] INFO: Test took 4540ms.
[15:52:34.550] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:34.551] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 806866
[15:52:34.551] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[15:52:34.551] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:52:34.612] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:35.277] INFO: Expecting 41600 events.
[15:52:39.174] INFO: 41600 events read in total (3370ms).
[15:52:39.179] INFO: Test took 4567ms.
[15:52:39.311] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:39.311] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 808549
[15:52:39.311] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[15:52:39.311] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:52:39.373] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:40.038] INFO: Expecting 41600 events.
[15:52:43.930] INFO: 41600 events read in total (3365ms).
[15:52:43.935] INFO: Test took 4562ms.
[15:52:44.067] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:44.067] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 809649
[15:52:44.067] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[15:52:44.068] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:52:44.130] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:44.795] INFO: Expecting 41600 events.
[15:52:48.682] INFO: 41600 events read in total (3361ms).
[15:52:48.687] INFO: Test took 4557ms.
[15:52:48.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:48.821] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 809991
[15:52:48.821] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[15:52:48.821] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:52:48.883] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:49.548] INFO: Expecting 41600 events.
[15:52:53.458] INFO: 41600 events read in total (3383ms).
[15:52:53.463] INFO: Test took 4580ms.
[15:52:53.598] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:53.598] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811669
[15:52:53.598] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[15:52:53.599] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:52:53.660] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:54.325] INFO: Expecting 41600 events.
[15:52:58.236] INFO: 41600 events read in total (3384ms).
[15:52:58.241] INFO: Test took 4581ms.
[15:52:58.375] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:58.375] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811860
[15:52:58.375] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[15:52:58.375] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:52:58.437] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:52:59.102] INFO: Expecting 41600 events.
[15:53:03.009] INFO: 41600 events read in total (3381ms).
[15:53:03.014] INFO: Test took 4577ms.
[15:53:03.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:03.146] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811951
[15:53:03.146] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[15:53:03.146] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:53:03.208] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:03.873] INFO: Expecting 41600 events.
[15:53:07.813] INFO: 41600 events read in total (3413ms).
[15:53:07.818] INFO: Test took 4610ms.
[15:53:07.952] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:07.952] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811186
[15:53:07.952] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[15:53:07.953] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:53:08.015] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:08.680] INFO: Expecting 41600 events.
[15:53:12.586] INFO: 41600 events read in total (3380ms).
[15:53:12.591] INFO: Test took 4576ms.
[15:53:12.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:12.723] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 812084
[15:53:12.723] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[15:53:12.724] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:53:12.786] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:13.451] INFO: Expecting 41600 events.
[15:53:17.363] INFO: 41600 events read in total (3385ms).
[15:53:17.368] INFO: Test took 4582ms.
[15:53:17.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:17.499] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 810189
[15:53:17.499] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[15:53:17.499] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:53:17.562] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:18.227] INFO: Expecting 41600 events.
[15:53:22.146] INFO: 41600 events read in total (3393ms).
[15:53:22.151] INFO: Test took 4589ms.
[15:53:22.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:22.283] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 809776
[15:53:22.283] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[15:53:22.283] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:53:22.346] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:23.012] INFO: Expecting 41600 events.
[15:53:26.930] INFO: 41600 events read in total (3392ms).
[15:53:26.935] INFO: Test took 4589ms.
[15:53:27.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:27.069] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811523
[15:53:27.069] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[15:53:27.070] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:53:27.133] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:27.799] INFO: Expecting 41600 events.
[15:53:31.707] INFO: 41600 events read in total (3381ms).
[15:53:31.712] INFO: Test took 4579ms.
[15:53:31.844] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:31.844] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 810576
[15:53:31.844] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[15:53:31.844] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:53:31.906] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:32.571] INFO: Expecting 41600 events.
[15:53:36.482] INFO: 41600 events read in total (3385ms).
[15:53:36.487] INFO: Test took 4581ms.
[15:53:36.622] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:36.622] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 809956
[15:53:36.622] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[15:53:36.623] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:53:36.684] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:37.349] INFO: Expecting 41600 events.
[15:53:41.267] INFO: 41600 events read in total (3392ms).
[15:53:41.272] INFO: Test took 4588ms.
[15:53:41.407] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:41.407] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 810716
[15:53:41.407] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[15:53:41.407] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:53:41.469] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:42.134] INFO: Expecting 41600 events.
[15:53:46.050] INFO: 41600 events read in total (3389ms).
[15:53:46.055] INFO: Test took 4586ms.
[15:53:46.188] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:46.188] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 811139
[15:53:46.188] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[15:53:46.188] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:53:46.251] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:46.918] INFO: Expecting 41600 events.
[15:53:50.826] INFO: 41600 events read in total (3382ms).
[15:53:50.831] INFO: Test took 4580ms.
[15:53:50.966] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:50.966] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 810472
[15:53:50.966] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[15:53:50.966] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:53:51.029] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:51.695] INFO: Expecting 41600 events.
[15:53:55.568] INFO: 41600 events read in total (3346ms).
[15:53:55.573] INFO: Test took 4544ms.
[15:53:55.705] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:55.705] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 808755
[15:53:55.705] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[15:53:55.705] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:53:55.768] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:53:56.433] INFO: Expecting 41600 events.
[15:54:00.196] INFO: 41600 events read in total (3236ms).
[15:54:00.201] INFO: Test took 4433ms.
[15:54:00.335] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:00.335] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 805195
[15:54:00.335] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[15:54:00.335] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:54:00.619] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 153 eff = 0.999423
[15:54:00.619] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 174 eff = 0.999375
[15:54:00.619] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 160 eff = 0.997115
[15:54:00.619] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 181 eff = 0.996899
[15:54:00.619] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 149 eff = 0.996466
[15:54:00.619] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 144 eff = 0.996154
[15:54:00.620] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 144 eff = 0.996659
[15:54:00.620] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 185 eff = 0.997043
[15:54:00.620] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 177 eff = 0.99649
[15:54:00.620] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 190 eff = 0.99601
[15:54:00.620] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 193 eff = 0.996851
[15:54:00.620] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 191 eff = 0.996995
[15:54:00.620] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 174 eff = 0.997188
[15:54:00.621] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 185 eff = 0.998389
[15:54:00.621] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 132 eff = 0.999327
[15:54:00.621] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 159 eff = 0.999327
[15:54:03.427] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[15:54:03.427] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[15:54:03.427] INFO: ----------------------------------------------------------------------
[15:54:03.427] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:54:03.427] INFO: ----------------------------------------------------------------------
[15:54:03.427] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[15:54:03.427] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[15:54:03.427] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[15:54:03.427] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[15:54:03.427] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[15:54:03.427] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[15:54:03.427] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[15:54:03.427] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[15:54:03.427] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[15:54:03.427] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[15:54:03.427] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[15:54:03.427] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[15:54:03.445] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[15:54:04.114] INFO: Expecting 208000 events.
[15:54:20.515] INFO: 208000 events read in total (15874ms).
[15:54:20.533] INFO: Test took 17088ms.
[15:54:21.183] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:21.183] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3749444
[15:54:21.183] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[15:54:21.183] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[15:54:21.663] INFO: number of dead pixels (per ROC): 0 0 4 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:54:21.663] INFO: number of red-efficiency pixels: 170 175 312 560 707 633 733 661 660 710 619 589 572 348 155 149
[15:54:21.664] INFO: number of X-ray hits detected: 123024 106328 178171 275092 289333 290805 294917 283673 305916 302581 292901 270281 270893 179610 103026 116337
[15:54:21.664] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:54:21.664] INFO: number of Vcal hits detected: 207822 207818 207450 207393 207203 207302 207159 207270 207262 207190 207275 207337 207364 207595 207839 207845
[15:54:21.664] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.7 99.6 99.7 99.7 99.6 99.7 99.7 99.7 99.8 99.9 99.9
[15:54:21.664] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.7 99.6 99.7 99.6 99.6 99.6 99.6 99.7 99.7 99.7 99.8 99.9 99.9
[15:54:21.664] INFO: X-ray hit rate [MHz/cm2]: 36.1 31.2 52.2 80.6 84.8 85.2 86.4 83.1 89.7 88.7 85.9 79.2 79.4 52.6 30.2 34.1
[15:54:21.664] INFO: PixTestHighRate::doXPixelAlive() done
[15:54:21.708] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[15:54:21.708] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[15:54:21.708] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[15:54:21.708] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[15:54:21.708] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[15:54:21.708] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[15:54:21.708] INFO: PixTest:: pg_setup set to default.
[15:54:28.099] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[15:54:28.099] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C10_V0 -> highRate_xraymap_mod
[15:54:30.907] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[15:54:30.907] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 390.7mA
[15:54:30.908] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 479.9mA
[15:54:30.908] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: 0.6 C
[15:54:30.908] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[15:54:30.908] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[15:54:30.908] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[15:54:30.908] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[15:54:30.908] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[15:54:30.908] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[15:54:30.909] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[15:54:30.909] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[15:54:30.909] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[15:54:30.909] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[15:54:31.002] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[15:54:31.002] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[15:54:31.011] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[15:54:31.011] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[15:54:31.011] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[15:54:31.011] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[15:54:31.011] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[15:54:31.011] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[15:54:31.014] QUIET: Connection to board 58 closed.
[15:54:32.216] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries