Test Date: 1973-11-29 16:33
Analysis date: 2016-10-24 14:43
Logfile
hrData_40.log
[11:35:47.574] INFO: *** Welcome to pxar ***
[11:35:47.574] INFO: *** Today: 2016/10/24
[11:35:47.597] INFO: *** Version: v1.9.0-818-g96727
[11:35:47.597] INFO: readRocDacs: data/mq132/dacParameters35_C0.dat .. data/mq132/dacParameters35_C15.dat
[11:35:47.598] INFO: readTbmDacs: data/mq132/tbmParameters_C0a.dat .. data/mq132/tbmParameters_C0b.dat
[11:35:47.598] INFO: readMaskFile: data/mq132/defaultMaskFile.dat
[11:35:47.598] INFO: readTrimFile: data/mq132/trimParameters35_C0.dat .. data/mq132/trimParameters35_C15.dat
[11:35:47.750] INFO: clk: 4
[11:35:47.750] INFO: ctr: 4
[11:35:47.750] INFO: sda: 19
[11:35:47.750] INFO: tin: 9
[11:35:47.750] INFO: level: 15
[11:35:47.750] INFO: triggerdelay: 0
[11:35:47.750] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:35:47.750] INFO: Log level: DEBUG
[11:35:47.761] QUIET: Connection to board DTB_WRPRHI opened.
[11:35:47.765] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[11:35:47.768] INFO: RPC call hashes of host and DTB match: 398089610
[11:35:49.375] INFO: DUT info:
[11:35:49.375] INFO: The DUT currently contains the following objects:
[11:35:49.375] INFO: 2 TBM Cores tbm08c (2 ON)
[11:35:49.375] INFO: TBM Core alpha (0): 7 registers set
[11:35:49.375] INFO: TBM Core beta (1): 7 registers set
[11:35:49.376] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:35:49.376] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:35:49.376] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:35:49.376] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:35:49.377] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:49.378] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:35:49.379] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:35:49.380] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:35:49.382] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33144832
[11:35:49.382] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x21be0e0
[11:35:49.382] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1f60370
[11:35:49.382] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f2f85d94010
[11:35:49.382] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f2f8bfff510
[11:35:49.382] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33153024 fPxarMemory = 0x7f2f85d94010
[11:35:49.383] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381.9mA
[11:35:49.385] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 472.7mA
[11:35:49.385] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:35:49.882] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x25acf70
[11:35:49.948] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:35:49.948] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:35:49.948] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:35:49.954] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:35:49.954] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:35:49.954] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:35:49.969] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:35:49.969] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:35:49.987] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:35:49.987] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:35:50.000] INFO: PixTestCmd::init()
[11:35:50.006] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:35:50.006] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:35:50.006] INFO: readGainPedestalParameters data/mq132/phCalibrationFitErr35_C0.dat .. data/mq132/phCalibrationFitErr35_C15.dat
[11:35:50.258] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:35:50.258] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:35:50.274] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:35:50.280] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:35:50.280] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:35:50.280] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:35:50.304] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:35:50.304] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:35:50.304] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:35:50.304] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:35:50.304] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:35:50.311] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:35:50.311] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:35:50.328] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:35:50.328] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:35:50.329] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:35:50.329] INFO: readReadbackCal: data/mq132/readbackCal_C0.dat .. data/mq132/readbackCal_C15.dat
[11:35:50.338] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:35:50.346] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:35:50.346] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:35:50.346] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:35:50.346] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:35:50.367] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:35:50.367] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:35:53.206] INFO: ######################################################################
[11:35:53.206] INFO: PixTestXray::doTest()
[11:35:53.206] INFO: ######################################################################
[11:35:53.206] INFO: ----------------------------------------------------------------------
[11:35:53.206] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:35:53.206] INFO: ----------------------------------------------------------------------
[11:35:54.180] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:36:05.974] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[11:36:05.978] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:36:33.166] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1161966 events.
[11:36:40.053] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1161966, pixels seen in all events: 6308602
[11:36:40.249] INFO: Resuming triggers.
[11:36:52.051] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[11:36:52.091] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:37:19.234] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1162604 events.
[11:37:26.043] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1162604, pixels seen in all events: 6300094
[11:37:26.220] INFO: Resuming triggers.
[11:37:38.016] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[11:37:38.057] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:38:05.176] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1162113 events.
[11:38:11.967] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1162113, pixels seen in all events: 6305908
[11:38:12.139] INFO: Resuming triggers.
[11:38:23.948] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[11:38:23.987] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:38:51.112] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163344 events.
[11:38:57.926] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163344, pixels seen in all events: 6290780
[11:38:58.188] INFO: Resuming triggers.
[11:39:09.995] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[11:39:10.035] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:39:37.151] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163135 events.
[11:39:44.030] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163135, pixels seen in all events: 6293205
[11:39:44.216] INFO: Resuming triggers.
[11:39:56.021] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[11:39:56.064] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:40:23.213] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1162962 events.
[11:40:30.090] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1162962, pixels seen in all events: 6295561
[11:40:30.273] INFO: Resuming triggers.
[11:40:42.079] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[11:40:42.122] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:41:09.308] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163085 events.
[11:41:16.201] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163085, pixels seen in all events: 6293990
[11:41:16.394] INFO: Resuming triggers.
[11:41:28.200] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[11:41:28.246] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:41:55.434] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1163134 events.
[11:42:02.347] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1163134, pixels seen in all events: 6293647
[11:42:02.534] INFO: Resuming triggers.
[11:42:08.238] INFO: data taking finished, elapsed time: 100 seconds.
[11:42:08.434] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:42:21.672] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 561913 events.
[11:42:24.962] DEBUG: <PixTestXray.cc/processData:L823> # events read: 561913, pixels seen in all events: 3039483
[11:42:25.072] INFO: PixTest:: pg_setup set to default.
[11:42:25.113] INFO: PixTestXray::doPhRun() done
[11:42:25.113] INFO: PixTestXray::doTest() done
[11:42:49.702] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:42:49.703] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[11:42:49.704] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 474.3mA
[11:42:49.704] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[11:42:49.704] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:42:49.704] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:42:49.704] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:42:49.704] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:42:49.704] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:42:49.704] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:42:49.704] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:42:49.704] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:42:49.705] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:42:49.705] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:42:49.705] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:42:49.705] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:42:49.705] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:42:49.705] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:42:49.705] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:42:49.705] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:42:49.705] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:42:49.705] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:42:49.999] QUIET: Connection to board 58 closed.
[11:42:50.081] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrData_120.log
[11:42:54.609] INFO: *** Welcome to pxar ***
[11:42:54.609] INFO: *** Today: 2016/10/24
[11:42:54.632] INFO: *** Version: v1.9.0-818-g96727
[11:42:54.632] INFO: readRocDacs: data/mq132/dacParameters35_C0.dat .. data/mq132/dacParameters35_C15.dat
[11:42:54.633] INFO: readTbmDacs: data/mq132/tbmParameters_C0a.dat .. data/mq132/tbmParameters_C0b.dat
[11:42:54.633] INFO: readMaskFile: data/mq132/defaultMaskFile.dat
[11:42:54.633] INFO: readTrimFile: data/mq132/trimParameters35_C0.dat .. data/mq132/trimParameters35_C15.dat
[11:42:54.783] INFO: clk: 4
[11:42:54.783] INFO: ctr: 4
[11:42:54.783] INFO: sda: 19
[11:42:54.783] INFO: tin: 9
[11:42:54.783] INFO: level: 15
[11:42:54.783] INFO: triggerdelay: 0
[11:42:54.783] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:42:54.783] INFO: Log level: DEBUG
[11:42:54.796] QUIET: Connection to board DTB_WRPRHI opened.
[11:42:54.799] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[11:42:54.802] INFO: RPC call hashes of host and DTB match: 398089610
[11:42:56.406] INFO: DUT info:
[11:42:56.406] INFO: The DUT currently contains the following objects:
[11:42:56.406] INFO: 2 TBM Cores tbm08c (2 ON)
[11:42:56.406] INFO: TBM Core alpha (0): 7 registers set
[11:42:56.406] INFO: TBM Core beta (1): 7 registers set
[11:42:56.406] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:42:56.406] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.406] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.406] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.406] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:42:56.407] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:42:56.407] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:42:56.407] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:42:56.407] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:42:56.407] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:42:56.407] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:42:56.407] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:42:56.407] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:42:56.407] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:42:56.407] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:42:56.407] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:42:56.407] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:42:56.408] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:42:56.409] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:42:56.410] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:42:56.411] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:42:56.413] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 33603584
[11:42:56.413] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x1c8f0e0
[11:42:56.413] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x1a31370
[11:42:56.413] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f8d45d94010
[11:42:56.413] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f8d4bfff510
[11:42:56.413] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 33611776 fPxarMemory = 0x7f8d45d94010
[11:42:56.414] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381.9mA
[11:42:56.416] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 473.5mA
[11:42:56.416] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:42:56.915] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x207df60
[11:42:56.985] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:42:56.986] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:42:56.986] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:42:56.991] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:42:56.991] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:42:56.992] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:42:57.002] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:42:57.003] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:42:57.015] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:42:57.015] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:42:57.036] INFO: PixTestCmd::init()
[11:42:57.043] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:42:57.043] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:42:57.043] INFO: readGainPedestalParameters data/mq132/phCalibrationFitErr35_C0.dat .. data/mq132/phCalibrationFitErr35_C15.dat
[11:42:57.295] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:42:57.295] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:42:57.309] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:42:57.315] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:42:57.315] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:42:57.315] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:42:57.339] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:42:57.340] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:42:57.340] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:42:57.340] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:42:57.340] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:42:57.346] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:42:57.346] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:42:57.366] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:42:57.366] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:42:57.367] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:42:57.367] INFO: readReadbackCal: data/mq132/readbackCal_C0.dat .. data/mq132/readbackCal_C15.dat
[11:42:57.378] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:42:57.386] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:42:57.386] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:42:57.386] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:42:57.387] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:42:57.407] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:42:57.407] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:43:05.302] INFO: ######################################################################
[11:43:05.302] INFO: PixTestXray::doTest()
[11:43:05.302] INFO: ######################################################################
[11:43:05.302] INFO: ----------------------------------------------------------------------
[11:43:05.302] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:43:05.302] INFO: ----------------------------------------------------------------------
[11:43:06.277] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:43:13.705] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[11:43:13.709] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:43:38.767] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 731886 events.
[11:43:51.055] DEBUG: <PixTestXray.cc/processData:L823> # events read: 731886, pixels seen in all events: 11471298
[11:43:51.292] INFO: Resuming triggers.
[11:43:58.724] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[11:43:58.767] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:44:23.763] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 732067 events.
[11:44:35.987] DEBUG: <PixTestXray.cc/processData:L823> # events read: 732067, pixels seen in all events: 11467407
[11:44:36.173] INFO: Resuming triggers.
[11:44:43.605] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[11:44:43.649] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:45:08.683] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 732155 events.
[11:45:20.959] DEBUG: <PixTestXray.cc/processData:L823> # events read: 732155, pixels seen in all events: 11465668
[11:45:21.176] INFO: Resuming triggers.
[11:45:28.607] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[11:45:28.651] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:45:53.705] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 731971 events.
[11:46:06.002] DEBUG: <PixTestXray.cc/processData:L823> # events read: 731971, pixels seen in all events: 11468490
[11:46:06.216] INFO: Resuming triggers.
[11:46:13.647] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[11:46:13.690] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:46:38.647] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 732067 events.
[11:46:50.902] DEBUG: <PixTestXray.cc/processData:L823> # events read: 732067, pixels seen in all events: 11467144
[11:46:51.115] INFO: Resuming triggers.
[11:46:58.545] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[11:46:58.589] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:47:23.562] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 731980 events.
[11:47:34.938] DEBUG: <PixTestXray.cc/processData:L823> # events read: 731980, pixels seen in all events: 11468531
[11:47:35.148] INFO: Resuming triggers.
[11:47:42.578] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[11:47:42.621] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:48:07.780] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 732045 events.
[11:48:19.227] DEBUG: <PixTestXray.cc/processData:L823> # events read: 732045, pixels seen in all events: 11467065
[11:48:19.434] INFO: Resuming triggers.
[11:48:26.867] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[11:48:26.908] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:48:52.017] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 732216 events.
[11:49:03.440] DEBUG: <PixTestXray.cc/processData:L823> # events read: 732216, pixels seen in all events: 11465970
[11:49:03.594] INFO: Resuming triggers.
[11:49:11.027] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[11:49:11.112] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:49:36.110] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 732216 events.
[11:49:47.503] DEBUG: <PixTestXray.cc/processData:L823> # events read: 732216, pixels seen in all events: 11465844
[11:49:47.708] INFO: Resuming triggers.
[11:49:55.143] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[11:49:55.183] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:50:20.197] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 732425 events.
[11:50:31.442] DEBUG: <PixTestXray.cc/processData:L823> # events read: 732425, pixels seen in all events: 11462340
[11:50:31.647] INFO: Resuming triggers.
[11:50:39.082] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[11:50:39.123] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:51:04.100] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 732536 events.
[11:51:15.421] DEBUG: <PixTestXray.cc/processData:L823> # events read: 732536, pixels seen in all events: 11461828
[11:51:15.629] INFO: Resuming triggers.
[11:51:23.066] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[11:51:23.109] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:51:48.061] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 732621 events.
[11:51:59.343] DEBUG: <PixTestXray.cc/processData:L823> # events read: 732621, pixels seen in all events: 11460494
[11:51:59.529] INFO: Resuming triggers.
[11:52:06.967] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[11:52:07.033] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:52:32.065] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 732658 events.
[11:52:43.336] DEBUG: <PixTestXray.cc/processData:L823> # events read: 732658, pixels seen in all events: 11460057
[11:52:43.545] INFO: Resuming triggers.
[11:52:47.056] INFO: data taking finished, elapsed time: 100 seconds.
[11:52:47.252] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[11:52:59.177] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 345891 events.
[11:53:04.528] DEBUG: <PixTestXray.cc/processData:L823> # events read: 345891, pixels seen in all events: 5410724
[11:53:04.628] INFO: PixTest:: pg_setup set to default.
[11:53:04.693] INFO: PixTestXray::doPhRun() done
[11:53:04.693] INFO: PixTestXray::doTest() done
[11:53:11.462] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:53:11.463] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[11:53:11.464] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 474.3mA
[11:53:11.464] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[11:53:11.464] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:53:11.464] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:53:11.464] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:53:11.464] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:53:11.464] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:53:11.465] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:53:11.465] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:53:11.465] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:53:11.465] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:53:11.465] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:53:11.465] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:53:11.465] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:53:11.465] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:53:11.465] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:53:11.466] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:53:11.466] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:53:11.466] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:53:11.466] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:53:11.784] QUIET: Connection to board 58 closed.
[11:53:11.863] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_40.log
[11:29:58.858] INFO: *** Welcome to pxar ***
[11:29:58.858] INFO: *** Today: 2016/10/24
[11:29:58.884] INFO: *** Version: v1.9.0-818-g96727
[11:29:58.884] INFO: readRocDacs: data/mq132/dacParameters35_C0.dat .. data/mq132/dacParameters35_C15.dat
[11:29:58.885] INFO: readTbmDacs: data/mq132/tbmParameters_C0a.dat .. data/mq132/tbmParameters_C0b.dat
[11:29:58.885] INFO: readMaskFile: data/mq132/defaultMaskFile.dat
[11:29:58.885] INFO: readTrimFile: data/mq132/trimParameters35_C0.dat .. data/mq132/trimParameters35_C15.dat
[11:29:59.034] INFO: clk: 4
[11:29:59.034] INFO: ctr: 4
[11:29:59.034] INFO: sda: 19
[11:29:59.034] INFO: tin: 9
[11:29:59.034] INFO: level: 15
[11:29:59.034] INFO: triggerdelay: 0
[11:29:59.034] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:29:59.034] INFO: Log level: DEBUG
[11:29:59.045] QUIET: Connection to board DTB_WRPRHI opened.
[11:29:59.048] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[11:29:59.051] INFO: RPC call hashes of host and DTB match: 398089610
[11:30:00.655] INFO: DUT info:
[11:30:00.655] INFO: The DUT currently contains the following objects:
[11:30:00.655] INFO: 2 TBM Cores tbm08c (2 ON)
[11:30:00.655] INFO: TBM Core alpha (0): 7 registers set
[11:30:00.655] INFO: TBM Core beta (1): 7 registers set
[11:30:00.655] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:30:00.655] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.655] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.655] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.655] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.655] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.655] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.655] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.655] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.655] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.655] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.656] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.656] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.656] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.656] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.656] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.656] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:30:00.656] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:30:00.657] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:30:00.658] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:30:00.659] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:30:00.660] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:30:00.660] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:30:00.660] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:30:00.660] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:30:00.660] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:30:00.662] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31354880
[11:30:00.662] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x284a0e0
[11:30:00.662] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x25ec370
[11:30:00.662] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f3109d94010
[11:30:00.662] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f310ffff510
[11:30:00.662] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31363072 fPxarMemory = 0x7f3109d94010
[11:30:00.663] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[11:30:00.664] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 473.5mA
[11:30:00.664] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.4 C
[11:30:01.160] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x2c39030
[11:30:01.227] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:30:01.227] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:30:01.227] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:30:01.233] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:30:01.233] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:30:01.233] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:30:01.242] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:30:01.242] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:30:01.258] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:30:01.258] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:30:01.277] INFO: PixTestCmd::init()
[11:30:01.290] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:30:01.290] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:30:01.290] INFO: readGainPedestalParameters data/mq132/phCalibrationFitErr35_C0.dat .. data/mq132/phCalibrationFitErr35_C15.dat
[11:30:01.541] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:30:01.541] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:30:01.561] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:30:01.569] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:30:01.569] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:30:01.569] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:30:01.594] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:30:01.594] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:30:01.594] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:30:01.594] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:30:01.594] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:30:01.601] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:30:01.601] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:30:01.622] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:30:01.622] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:30:01.622] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:30:01.622] INFO: readReadbackCal: data/mq132/readbackCal_C0.dat .. data/mq132/readbackCal_C15.dat
[11:30:01.631] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:30:01.647] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:30:01.647] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:30:01.647] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:30:01.647] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:30:01.659] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:30:01.660] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:30:06.265] INFO: ######################################################################
[11:30:06.265] INFO: PixTestAlive::doTest()
[11:30:06.265] INFO: ######################################################################
[11:30:06.269] INFO: ----------------------------------------------------------------------
[11:30:06.269] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:30:06.269] INFO: ----------------------------------------------------------------------
[11:30:06.271] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:06.650] INFO: Expecting 41600 events.
[11:30:11.031] INFO: 41600 events read in total (3663ms).
[11:30:11.179] INFO: Test took 4908ms.
[11:30:11.190] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:11.190] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66552
[11:30:11.190] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:30:11.469] INFO: PixTestAlive::aliveTest() done
[11:30:11.469] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 2 0 0 0 1 0 5 0
[11:30:11.469] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 22 20 19 34 47 54 35 40 37 35 45 28 37 22 18 17
[11:30:11.497] INFO: ----------------------------------------------------------------------
[11:30:11.497] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:30:11.497] INFO: ----------------------------------------------------------------------
[11:30:11.499] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:11.877] INFO: Expecting 41600 events.
[11:30:14.992] INFO: 41600 events read in total (2400ms).
[11:30:14.992] INFO: Test took 3493ms.
[11:30:14.992] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:14.992] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:30:14.992] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:30:14.993] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:30:15.415] INFO: PixTestAlive::maskTest() done
[11:30:15.415] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:30:15.437] INFO: ----------------------------------------------------------------------
[11:30:15.438] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:30:15.438] INFO: ----------------------------------------------------------------------
[11:30:15.440] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:15.817] INFO: Expecting 41600 events.
[11:30:20.199] INFO: 41600 events read in total (3666ms).
[11:30:20.201] INFO: Test took 4761ms.
[11:30:20.211] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:20.212] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66552
[11:30:20.212] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:30:20.583] INFO: PixTestAlive::addressDecodingTest() done
[11:30:20.583] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:30:20.583] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[11:30:23.014] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:30:23.014] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:30:23.014] INFO: ----------------------------------------------------------------------
[11:30:23.014] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:30:23.014] INFO: ----------------------------------------------------------------------
[11:30:23.153] INFO: Expecting 768 events.
[11:30:24.287] INFO: 768 events read in total (419ms).
[11:30:24.287] INFO: Test took 1267ms.
[11:30:24.293] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:25.090] INFO: Expecting 41600 events.
[11:30:28.469] INFO: 41600 events read in total (2853ms).
[11:30:28.474] INFO: Test took 4181ms.
[11:30:28.560] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:28.560] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 493861
[11:30:28.560] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:30:28.560] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:28.600] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:29.265] INFO: Expecting 41600 events.
[11:30:32.750] INFO: 41600 events read in total (2959ms).
[11:30:32.754] INFO: Test took 4154ms.
[11:30:32.835] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:32.835] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 501954
[11:30:32.835] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:30:32.836] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:32.876] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:33.541] INFO: Expecting 41600 events.
[11:30:37.062] INFO: 41600 events read in total (2994ms).
[11:30:37.066] INFO: Test took 4190ms.
[11:30:37.148] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:37.148] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 503778
[11:30:37.148] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:30:37.148] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:37.189] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:37.854] INFO: Expecting 41600 events.
[11:30:41.386] INFO: 41600 events read in total (3006ms).
[11:30:41.390] INFO: Test took 4201ms.
[11:30:41.472] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:41.472] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 503697
[11:30:41.472] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:30:41.472] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:41.513] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:42.178] INFO: Expecting 41600 events.
[11:30:45.717] INFO: 41600 events read in total (3013ms).
[11:30:45.721] INFO: Test took 4208ms.
[11:30:45.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:45.806] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 503751
[11:30:45.806] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:30:45.807] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:45.849] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:46.516] INFO: Expecting 41600 events.
[11:30:50.053] INFO: 41600 events read in total (3010ms).
[11:30:50.057] INFO: Test took 4208ms.
[11:30:50.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:50.140] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 504471
[11:30:50.140] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:30:50.140] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:50.182] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:50.847] INFO: Expecting 41600 events.
[11:30:54.388] INFO: 41600 events read in total (3014ms).
[11:30:54.391] INFO: Test took 4209ms.
[11:30:54.475] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:54.475] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 503486
[11:30:54.475] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:30:54.475] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:54.518] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:55.185] INFO: Expecting 41600 events.
[11:30:58.724] INFO: 41600 events read in total (3012ms).
[11:30:58.728] INFO: Test took 4210ms.
[11:30:58.811] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:30:58.811] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 503213
[11:30:58.811] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:30:58.811] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:30:58.853] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:30:59.518] INFO: Expecting 41600 events.
[11:31:03.056] INFO: 41600 events read in total (3011ms).
[11:31:03.060] INFO: Test took 4207ms.
[11:31:03.144] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:03.144] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 503404
[11:31:03.145] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:31:03.145] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:03.188] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:31:03.855] INFO: Expecting 41600 events.
[11:31:07.394] INFO: 41600 events read in total (3012ms).
[11:31:07.398] INFO: Test took 4210ms.
[11:31:07.480] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:07.481] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 503819
[11:31:07.481] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:31:07.481] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:07.522] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:31:08.187] INFO: Expecting 41600 events.
[11:31:11.734] INFO: 41600 events read in total (3020ms).
[11:31:11.738] INFO: Test took 4216ms.
[11:31:11.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:11.821] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 503613
[11:31:11.821] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:31:11.821] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:11.862] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:31:12.528] INFO: Expecting 41600 events.
[11:31:16.068] INFO: 41600 events read in total (3014ms).
[11:31:16.071] INFO: Test took 4209ms.
[11:31:16.153] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:16.153] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 503355
[11:31:16.153] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:31:16.153] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:16.195] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:31:16.860] INFO: Expecting 41600 events.
[11:31:20.396] INFO: 41600 events read in total (3009ms).
[11:31:20.400] INFO: Test took 4205ms.
[11:31:20.484] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:20.484] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 503954
[11:31:20.484] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:31:20.485] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:20.526] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:31:21.191] INFO: Expecting 41600 events.
[11:31:24.736] INFO: 41600 events read in total (3018ms).
[11:31:24.740] INFO: Test took 4214ms.
[11:31:24.823] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:24.823] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 504049
[11:31:24.823] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:31:24.824] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:24.865] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:31:25.530] INFO: Expecting 41600 events.
[11:31:29.068] INFO: 41600 events read in total (3011ms).
[11:31:29.071] INFO: Test took 4206ms.
[11:31:29.155] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:29.155] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 503506
[11:31:29.156] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:31:29.156] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:29.199] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:31:29.865] INFO: Expecting 41600 events.
[11:31:33.402] INFO: 41600 events read in total (3010ms).
[11:31:33.406] INFO: Test took 4207ms.
[11:31:33.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:33.488] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 501706
[11:31:33.488] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:31:33.489] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:33.530] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:31:34.196] INFO: Expecting 41600 events.
[11:31:37.733] INFO: 41600 events read in total (3010ms).
[11:31:37.737] INFO: Test took 4207ms.
[11:31:37.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:37.821] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 503288
[11:31:37.821] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:31:37.821] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:37.863] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:31:38.528] INFO: Expecting 41600 events.
[11:31:42.057] INFO: 41600 events read in total (3002ms).
[11:31:42.061] INFO: Test took 4198ms.
[11:31:42.144] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:42.144] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 502444
[11:31:42.144] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:31:42.144] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:42.186] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:31:42.851] INFO: Expecting 41600 events.
[11:31:46.320] INFO: 41600 events read in total (2943ms).
[11:31:46.324] INFO: Test took 4138ms.
[11:31:46.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:46.405] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 501705
[11:31:46.405] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:31:46.405] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:46.447] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:31:47.112] INFO: Expecting 41600 events.
[11:31:50.431] INFO: 41600 events read in total (2792ms).
[11:31:50.435] INFO: Test took 3988ms.
[11:31:50.518] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:31:50.518] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 492270
[11:31:50.518] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:31:50.518] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:31:50.781] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 147 eff = 0.999591
[11:31:50.781] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 135 eff = 0.99976
[11:31:50.782] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 171 eff = 0.999327
[11:31:50.782] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 157 eff = 0.99875
[11:31:50.782] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 151 eff = 0.998678
[11:31:50.782] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 169 eff = 0.998894
[11:31:50.783] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 170 eff = 0.99899
[11:31:50.783] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 148 eff = 0.999495
[11:31:50.783] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 171 eff = 0.998269
[11:31:50.783] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 177 eff = 0.998534
[11:31:50.784] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 141 eff = 0.998822
[11:31:50.784] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 150 eff = 0.998774
[11:31:50.784] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 162 eff = 0.99875
[11:31:50.784] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 152 eff = 0.999375
[11:31:50.785] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 143 eff = 0.99863
[11:31:50.785] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 144 eff = 0.999688
[11:31:53.558] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:31:53.558] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:31:53.558] INFO: ----------------------------------------------------------------------
[11:31:53.558] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:31:53.558] INFO: ----------------------------------------------------------------------
[11:31:53.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:31:53.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:31:53.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:31:53.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:31:53.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:31:53.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:31:53.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:31:53.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:31:53.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:31:53.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:31:53.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:31:53.559] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:31:53.575] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:31:54.243] INFO: Expecting 208000 events.
[11:32:07.879] INFO: 208000 events read in total (13110ms).
[11:32:07.891] INFO: Test took 14316ms.
[11:32:08.292] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:08.292] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2233658
[11:32:08.292] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:32:08.292] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:32:08.672] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 2 0 0 0 1 0 5 0
[11:32:08.672] INFO: number of red-efficiency pixels: 140 97 157 263 281 305 294 222 310 251 245 281 217 128 78 105
[11:32:08.672] INFO: number of X-ray hits detected: 82750 62131 104957 164542 172887 169219 172957 159507 171131 174847 172939 161650 157511 104440 61302 74336
[11:32:08.672] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:32:08.672] INFO: number of Vcal hits detected: 207854 207902 207833 207724 207707 207680 207691 207772 207575 207716 207748 207699 207724 207869 207674 207894
[11:32:08.672] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[11:32:08.672] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.8 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.8 99.9
[11:32:08.672] INFO: X-ray hit rate [MHz/cm2]: 24.3 18.2 30.8 48.2 50.7 49.6 50.7 46.8 50.2 51.2 50.7 47.4 46.2 30.6 18.0 21.8
[11:32:08.672] INFO: PixTestHighRate::doXPixelAlive() done
[11:32:08.717] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:32:08.717] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:32:08.717] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:32:08.717] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:32:08.717] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:32:08.717] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:32:08.717] INFO: PixTest:: pg_setup set to default.
[11:32:43.638] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:32:43.639] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[11:32:43.640] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 474.3mA
[11:32:43.640] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[11:32:43.640] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:32:43.640] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:32:43.640] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:32:43.640] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:32:43.640] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:32:43.640] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:32:43.640] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:32:43.640] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:32:43.640] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:32:43.641] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:32:43.726] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:32:43.726] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:32:43.735] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:32:43.735] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:32:43.735] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:32:43.735] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:32:43.735] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:32:43.735] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:32:43.738] QUIET: Connection to board 58 closed.
[11:32:43.817] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_80.log
[11:26:57.691] INFO: *** Welcome to pxar ***
[11:26:57.691] INFO: *** Today: 2016/10/24
[11:26:57.754] INFO: *** Version: v1.9.0-818-g96727
[11:26:57.754] INFO: readRocDacs: data/mq132/dacParameters35_C0.dat .. data/mq132/dacParameters35_C15.dat
[11:26:57.755] INFO: readTbmDacs: data/mq132/tbmParameters_C0a.dat .. data/mq132/tbmParameters_C0b.dat
[11:26:57.755] INFO: readMaskFile: data/mq132/defaultMaskFile.dat
[11:26:57.755] INFO: readTrimFile: data/mq132/trimParameters35_C0.dat .. data/mq132/trimParameters35_C15.dat
[11:26:57.904] INFO: clk: 4
[11:26:57.904] INFO: ctr: 4
[11:26:57.904] INFO: sda: 19
[11:26:57.904] INFO: tin: 9
[11:26:57.904] INFO: level: 15
[11:26:57.904] INFO: triggerdelay: 0
[11:26:57.904] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:26:57.904] INFO: Log level: DEBUG
[11:26:57.916] QUIET: Connection to board DTB_WRPRHI opened.
[11:26:57.920] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[11:26:57.922] INFO: RPC call hashes of host and DTB match: 398089610
[11:26:59.526] INFO: DUT info:
[11:26:59.526] INFO: The DUT currently contains the following objects:
[11:26:59.526] INFO: 2 TBM Cores tbm08c (2 ON)
[11:26:59.526] INFO: TBM Core alpha (0): 7 registers set
[11:26:59.526] INFO: TBM Core beta (1): 7 registers set
[11:26:59.527] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:26:59.527] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:26:59.527] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:26:59.527] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:26:59.527] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:26:59.527] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:26:59.527] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:26:59.528] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:26:59.529] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:26:59.530] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:26:59.531] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:26:59.533] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35311616
[11:26:59.533] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x25e70e0
[11:26:59.533] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0x2389370
[11:26:59.533] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f7e29d94010
[11:26:59.533] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f7e2ffff510
[11:26:59.533] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35319808 fPxarMemory = 0x7f7e29d94010
[11:26:59.534] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[11:26:59.536] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 473.5mA
[11:26:59.536] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[11:27:00.047] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x29d5fd0
[11:27:00.114] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:27:00.114] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:27:00.114] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:27:00.120] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:27:00.120] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:27:00.120] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:27:00.132] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:27:00.132] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:27:00.155] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:27:00.155] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:27:00.174] INFO: PixTestCmd::init()
[11:27:00.184] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:27:00.184] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:27:00.184] INFO: readGainPedestalParameters data/mq132/phCalibrationFitErr35_C0.dat .. data/mq132/phCalibrationFitErr35_C15.dat
[11:27:00.439] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:27:00.439] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:27:00.454] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:27:00.460] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:27:00.460] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:27:00.460] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:27:00.483] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:27:00.484] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:27:00.484] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:27:00.484] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:27:00.484] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:27:00.491] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:27:00.491] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:27:00.509] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:27:00.509] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:27:00.510] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:27:00.510] INFO: readReadbackCal: data/mq132/readbackCal_C0.dat .. data/mq132/readbackCal_C15.dat
[11:27:00.521] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:27:00.529] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:27:00.529] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:27:00.529] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:27:00.529] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:27:00.550] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:27:00.550] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:27:06.409] INFO: ######################################################################
[11:27:06.409] INFO: PixTestAlive::doTest()
[11:27:06.409] INFO: ######################################################################
[11:27:06.413] INFO: ----------------------------------------------------------------------
[11:27:06.413] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:27:06.413] INFO: ----------------------------------------------------------------------
[11:27:06.415] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:06.793] INFO: Expecting 41600 events.
[11:27:11.187] INFO: 41600 events read in total (3676ms).
[11:27:11.333] INFO: Test took 4918ms.
[11:27:11.343] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:11.343] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66552
[11:27:11.343] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:27:11.624] INFO: PixTestAlive::aliveTest() done
[11:27:11.624] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 2 0 0 0 1 0 5 0
[11:27:11.624] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 33 22 42 61 86 73 61 65 80 58 73 56 58 30 35 32
[11:27:11.653] INFO: ----------------------------------------------------------------------
[11:27:11.653] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:27:11.653] INFO: ----------------------------------------------------------------------
[11:27:11.655] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:12.034] INFO: Expecting 41600 events.
[11:27:15.150] INFO: 41600 events read in total (2401ms).
[11:27:15.151] INFO: Test took 3496ms.
[11:27:15.151] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:15.151] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:27:15.151] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:27:15.151] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:27:15.573] INFO: PixTestAlive::maskTest() done
[11:27:15.573] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:27:15.596] INFO: ----------------------------------------------------------------------
[11:27:15.596] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:27:15.596] INFO: ----------------------------------------------------------------------
[11:27:15.599] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:15.977] INFO: Expecting 41600 events.
[11:27:20.371] INFO: 41600 events read in total (3679ms).
[11:27:20.373] INFO: Test took 4774ms.
[11:27:20.384] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:20.384] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66552
[11:27:20.384] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:27:20.754] INFO: PixTestAlive::addressDecodingTest() done
[11:27:20.755] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:27:20.755] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[11:27:22.438] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:27:22.438] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:27:22.438] INFO: ----------------------------------------------------------------------
[11:27:22.438] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:27:22.438] INFO: ----------------------------------------------------------------------
[11:27:22.577] INFO: Expecting 768 events.
[11:27:23.710] INFO: 768 events read in total (417ms).
[11:27:23.710] INFO: Test took 1266ms.
[11:27:23.716] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:24.514] INFO: Expecting 41600 events.
[11:27:28.296] INFO: 41600 events read in total (3255ms).
[11:27:28.301] INFO: Test took 4585ms.
[11:27:28.430] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:28.430] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 740875
[11:27:28.430] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:27:28.431] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:27:28.486] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:29.151] INFO: Expecting 41600 events.
[11:27:32.991] INFO: 41600 events read in total (3313ms).
[11:27:32.996] INFO: Test took 4510ms.
[11:27:33.123] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:33.123] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 744215
[11:27:33.123] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:27:33.123] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:27:33.181] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:33.848] INFO: Expecting 41600 events.
[11:27:37.736] INFO: 41600 events read in total (3361ms).
[11:27:37.741] INFO: Test took 4560ms.
[11:27:37.867] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:37.867] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 748590
[11:27:37.867] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:27:37.867] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:27:37.925] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:38.591] INFO: Expecting 41600 events.
[11:27:42.469] INFO: 41600 events read in total (3352ms).
[11:27:42.474] INFO: Test took 4549ms.
[11:27:42.601] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:42.601] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 747376
[11:27:42.601] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:27:42.601] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:27:42.661] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:43.328] INFO: Expecting 41600 events.
[11:27:47.212] INFO: 41600 events read in total (3358ms).
[11:27:47.216] INFO: Test took 4555ms.
[11:27:47.343] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:47.343] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 747266
[11:27:47.343] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:27:47.344] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:27:47.402] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:48.069] INFO: Expecting 41600 events.
[11:27:51.948] INFO: 41600 events read in total (3352ms).
[11:27:51.953] INFO: Test took 4551ms.
[11:27:52.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:52.075] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 746899
[11:27:52.075] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:27:52.076] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:27:52.133] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:52.798] INFO: Expecting 41600 events.
[11:27:56.690] INFO: 41600 events read in total (3366ms).
[11:27:56.694] INFO: Test took 4561ms.
[11:27:56.818] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:56.818] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 748257
[11:27:56.818] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:27:56.818] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:27:56.875] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:27:57.540] INFO: Expecting 41600 events.
[11:28:01.440] INFO: 41600 events read in total (3373ms).
[11:28:01.445] INFO: Test took 4570ms.
[11:28:01.571] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:01.571] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 746137
[11:28:01.571] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:28:01.572] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:01.630] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:02.297] INFO: Expecting 41600 events.
[11:28:06.195] INFO: 41600 events read in total (3371ms).
[11:28:06.200] INFO: Test took 4570ms.
[11:28:06.327] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:06.327] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 747246
[11:28:06.327] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:28:06.328] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:06.386] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:07.053] INFO: Expecting 41600 events.
[11:28:10.947] INFO: 41600 events read in total (3367ms).
[11:28:10.952] INFO: Test took 4566ms.
[11:28:11.077] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:11.077] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 748845
[11:28:11.077] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:28:11.077] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:11.134] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:11.800] INFO: Expecting 41600 events.
[11:28:15.696] INFO: 41600 events read in total (3370ms).
[11:28:15.700] INFO: Test took 4566ms.
[11:28:15.826] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:15.826] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 750091
[11:28:15.826] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:28:15.827] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:15.886] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:16.553] INFO: Expecting 41600 events.
[11:28:20.447] INFO: 41600 events read in total (3368ms).
[11:28:20.452] INFO: Test took 4566ms.
[11:28:20.576] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:20.576] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 747596
[11:28:20.576] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:28:20.576] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:20.633] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:21.298] INFO: Expecting 41600 events.
[11:28:25.212] INFO: 41600 events read in total (3387ms).
[11:28:25.217] INFO: Test took 4584ms.
[11:28:25.341] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:25.341] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 750178
[11:28:25.341] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:28:25.342] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:25.399] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:26.064] INFO: Expecting 41600 events.
[11:28:29.925] INFO: 41600 events read in total (3334ms).
[11:28:29.930] INFO: Test took 4531ms.
[11:28:30.056] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:30.056] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 747540
[11:28:30.056] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:28:30.056] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:30.115] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:30.782] INFO: Expecting 41600 events.
[11:28:34.662] INFO: 41600 events read in total (3353ms).
[11:28:34.666] INFO: Test took 4551ms.
[11:28:34.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:34.793] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 747825
[11:28:34.793] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:28:34.793] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:34.852] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:35.520] INFO: Expecting 41600 events.
[11:28:39.418] INFO: 41600 events read in total (3372ms).
[11:28:39.422] INFO: Test took 4569ms.
[11:28:39.548] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:39.549] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 746896
[11:28:39.549] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:28:39.549] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:39.606] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:40.272] INFO: Expecting 41600 events.
[11:28:44.162] INFO: 41600 events read in total (3364ms).
[11:28:44.167] INFO: Test took 4560ms.
[11:28:44.291] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:44.291] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 747449
[11:28:44.291] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:28:44.292] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:44.349] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:45.014] INFO: Expecting 41600 events.
[11:28:48.903] INFO: 41600 events read in total (3362ms).
[11:28:48.908] INFO: Test took 4559ms.
[11:28:49.033] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:49.033] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 746632
[11:28:49.033] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:28:49.033] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:49.090] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:49.756] INFO: Expecting 41600 events.
[11:28:53.576] INFO: 41600 events read in total (3294ms).
[11:28:53.581] INFO: Test took 4490ms.
[11:28:53.703] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:53.703] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 745447
[11:28:53.703] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:28:53.704] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:53.762] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:28:54.427] INFO: Expecting 41600 events.
[11:28:58.089] INFO: 41600 events read in total (3135ms).
[11:28:58.094] INFO: Test took 4332ms.
[11:28:58.215] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:28:58.215] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 737453
[11:28:58.215] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:28:58.215] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:28:58.493] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 166 eff = 0.999303
[11:28:58.493] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 156 eff = 0.999375
[11:28:58.493] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 166 eff = 0.998293
[11:28:58.494] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 159 eff = 0.997308
[11:28:58.494] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 151 eff = 0.997091
[11:28:58.494] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 175 eff = 0.997332
[11:28:58.494] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 172 eff = 0.997452
[11:28:58.494] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 153 eff = 0.997428
[11:28:58.494] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 177 eff = 0.996755
[11:28:58.495] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 177 eff = 0.997067
[11:28:58.495] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 144 eff = 0.997115
[11:28:58.495] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 160 eff = 0.997668
[11:28:58.495] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 163 eff = 0.997596
[11:28:58.495] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 147 eff = 0.998774
[11:28:58.496] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 131 eff = 0.998293
[11:28:58.496] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 141 eff = 0.999423
[11:29:13.206] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:29:13.206] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:29:13.206] INFO: ----------------------------------------------------------------------
[11:29:13.206] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:29:13.206] INFO: ----------------------------------------------------------------------
[11:29:13.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:29:13.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:29:13.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:29:13.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:29:13.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:29:13.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:29:13.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:29:13.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:29:13.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:29:13.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:29:13.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:29:13.207] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:29:13.224] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:29:13.896] INFO: Expecting 208000 events.
[11:29:29.552] INFO: 208000 events read in total (15129ms).
[11:29:29.570] INFO: Test took 16345ms.
[11:29:30.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:29:30.162] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3438665
[11:29:30.162] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:29:30.162] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:29:30.630] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 2 0 0 0 1 0 5 0
[11:29:30.630] INFO: number of red-efficiency pixels: 192 122 314 584 602 490 587 486 597 573 580 521 445 295 149 157
[11:29:30.630] INFO: number of X-ray hits detected: 129415 96563 162903 254831 267806 264177 269075 247779 268173 271960 269925 252225 243610 161807 96774 115090
[11:29:30.630] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:29:30.630] INFO: number of Vcal hits detected: 207805 207875 207654 207347 207341 207457 207352 207469 207245 207331 207289 207430 207472 207679 207600 207837
[11:29:30.630] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.8 99.7 99.8 99.7 99.7 99.7 99.8 99.8 99.9 99.9 99.9
[11:29:30.630] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.6 99.7 99.7 99.7 99.7 99.8 99.8 99.9
[11:29:30.630] INFO: X-ray hit rate [MHz/cm2]: 37.9 28.3 47.7 74.7 78.5 77.4 78.9 72.6 78.6 79.7 79.1 73.9 71.4 47.4 28.4 33.7
[11:29:30.630] INFO: PixTestHighRate::doXPixelAlive() done
[11:29:30.675] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:29:30.675] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:29:30.675] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:29:30.675] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:29:30.675] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:29:30.676] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:29:30.676] INFO: PixTest:: pg_setup set to default.
[11:29:38.662] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:29:38.663] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[11:29:38.664] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 474.3mA
[11:29:38.664] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[11:29:38.664] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:29:38.664] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:29:38.664] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:29:38.664] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:29:38.664] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:29:38.664] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:29:38.664] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:29:38.664] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:29:38.664] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:29:38.664] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:29:38.748] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:29:38.748] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:29:38.757] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:29:38.757] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:29:38.757] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:29:38.757] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:29:38.757] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:29:38.757] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:29:38.760] QUIET: Connection to board 58 closed.
[11:29:38.839] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
hrEff_120.log
[11:23:23.850] INFO: *** Welcome to pxar ***
[11:23:23.850] INFO: *** Today: 2016/10/24
[11:23:23.876] INFO: *** Version: v1.9.0-818-g96727
[11:23:23.876] INFO: readRocDacs: data/mq132/dacParameters35_C0.dat .. data/mq132/dacParameters35_C15.dat
[11:23:23.877] INFO: readTbmDacs: data/mq132/tbmParameters_C0a.dat .. data/mq132/tbmParameters_C0b.dat
[11:23:23.877] INFO: readMaskFile: data/mq132/defaultMaskFile.dat
[11:23:23.877] INFO: readTrimFile: data/mq132/trimParameters35_C0.dat .. data/mq132/trimParameters35_C15.dat
[11:23:24.030] INFO: clk: 4
[11:23:24.030] INFO: ctr: 4
[11:23:24.030] INFO: sda: 19
[11:23:24.030] INFO: tin: 9
[11:23:24.030] INFO: level: 15
[11:23:24.030] INFO: triggerdelay: 0
[11:23:24.030] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[11:23:24.030] INFO: Log level: DEBUG
[11:23:24.041] QUIET: Connection to board DTB_WRPRHI opened.
[11:23:24.044] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[11:23:24.047] INFO: RPC call hashes of host and DTB match: 398089610
[11:23:25.650] INFO: DUT info:
[11:23:25.650] INFO: The DUT currently contains the following objects:
[11:23:25.650] INFO: 2 TBM Cores tbm08c (2 ON)
[11:23:25.650] INFO: TBM Core alpha (0): 7 registers set
[11:23:25.650] INFO: TBM Core beta (1): 7 registers set
[11:23:25.650] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:23:25.650] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.650] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[11:23:25.651] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[11:23:25.652] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[11:23:25.653] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[11:23:25.654] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[11:23:25.657] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 31641600
[11:23:25.657] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x105d0e0
[11:23:25.657] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xdff370
[11:23:25.657] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f0abdd94010
[11:23:25.657] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f0ac3fff510
[11:23:25.657] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 31649792 fPxarMemory = 0x7f0abdd94010
[11:23:25.658] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 381mA
[11:23:25.659] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 473.5mA
[11:23:25.659] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[11:23:26.158] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x144bf10
[11:23:26.228] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[11:23:26.228] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[11:23:26.228] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[11:23:26.234] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[11:23:26.234] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[11:23:26.234] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[11:23:26.246] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[11:23:26.246] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[11:23:26.263] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[11:23:26.263] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[11:23:26.275] INFO: PixTestCmd::init()
[11:23:26.294] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[11:23:26.294] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[11:23:26.294] INFO: readGainPedestalParameters data/mq132/phCalibrationFitErr35_C0.dat .. data/mq132/phCalibrationFitErr35_C15.dat
[11:23:26.546] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[11:23:26.546] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[11:23:26.560] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[11:23:26.567] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[11:23:26.567] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[11:23:26.568] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[11:23:26.591] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[11:23:26.591] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[11:23:26.591] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[11:23:26.591] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[11:23:26.591] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[11:23:26.597] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[11:23:26.597] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[11:23:26.619] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[11:23:26.619] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[11:23:26.619] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[11:23:26.619] INFO: readReadbackCal: data/mq132/readbackCal_C0.dat .. data/mq132/readbackCal_C15.dat
[11:23:26.629] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[11:23:26.638] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[11:23:26.638] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[11:23:26.638] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[11:23:26.638] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[11:23:26.658] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[11:23:26.658] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[11:23:30.433] INFO: ######################################################################
[11:23:30.433] INFO: PixTestAlive::doTest()
[11:23:30.433] INFO: ######################################################################
[11:23:30.437] INFO: ----------------------------------------------------------------------
[11:23:30.437] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:23:30.437] INFO: ----------------------------------------------------------------------
[11:23:30.439] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:23:30.820] INFO: Expecting 41600 events.
[11:23:35.232] INFO: 41600 events read in total (3694ms).
[11:23:35.378] INFO: Test took 4939ms.
[11:23:35.390] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:35.390] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66552
[11:23:35.390] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[11:23:35.669] INFO: PixTestAlive::aliveTest() done
[11:23:35.669] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 2 0 0 0 1 0 5 0
[11:23:35.669] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 51 23 54 81 96 85 95 69 87 86 75 58 68 41 37 32
[11:23:35.694] INFO: ----------------------------------------------------------------------
[11:23:35.694] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:23:35.694] INFO: ----------------------------------------------------------------------
[11:23:35.697] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:23:36.076] INFO: Expecting 41600 events.
[11:23:39.204] INFO: 41600 events read in total (2413ms).
[11:23:39.204] INFO: Test took 3507ms.
[11:23:39.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:39.204] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[11:23:39.204] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[11:23:39.205] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:23:39.627] INFO: PixTestAlive::maskTest() done
[11:23:39.627] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:23:39.650] INFO: ----------------------------------------------------------------------
[11:23:39.650] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:23:39.650] INFO: ----------------------------------------------------------------------
[11:23:39.652] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:23:40.029] INFO: Expecting 41600 events.
[11:23:44.441] INFO: 41600 events read in total (3697ms).
[11:23:44.442] INFO: Test took 4790ms.
[11:23:44.454] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:44.454] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66552
[11:23:44.454] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[11:23:44.824] INFO: PixTestAlive::addressDecodingTest() done
[11:23:44.824] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:23:44.824] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[11:23:46.638] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[11:23:46.638] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[11:23:46.638] INFO: ----------------------------------------------------------------------
[11:23:46.638] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:23:46.638] INFO: ----------------------------------------------------------------------
[11:23:46.778] INFO: Expecting 768 events.
[11:23:47.911] INFO: 768 events read in total (418ms).
[11:23:47.911] INFO: Test took 1267ms.
[11:23:47.917] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:23:48.715] INFO: Expecting 41600 events.
[11:23:52.632] INFO: 41600 events read in total (3391ms).
[11:23:52.637] INFO: Test took 4720ms.
[11:23:52.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:52.795] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 902483
[11:23:52.795] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[11:23:52.796] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:23:52.864] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:23:53.531] INFO: Expecting 41600 events.
[11:23:57.501] INFO: 41600 events read in total (3443ms).
[11:23:57.507] INFO: Test took 4643ms.
[11:23:57.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:57.657] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 911182
[11:23:57.658] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[11:23:57.658] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:23:57.729] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:23:58.397] INFO: Expecting 41600 events.
[11:24:02.385] INFO: 41600 events read in total (3461ms).
[11:24:02.391] INFO: Test took 4662ms.
[11:24:02.540] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:02.540] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 913755
[11:24:02.540] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[11:24:02.541] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:24:02.610] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:24:03.275] INFO: Expecting 41600 events.
[11:24:07.293] INFO: 41600 events read in total (3491ms).
[11:24:07.299] INFO: Test took 4689ms.
[11:24:07.446] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:07.446] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912009
[11:24:07.446] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[11:24:07.447] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:24:07.516] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:24:08.183] INFO: Expecting 41600 events.
[11:24:12.189] INFO: 41600 events read in total (3480ms).
[11:24:12.195] INFO: Test took 4679ms.
[11:24:12.341] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:12.341] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 910132
[11:24:12.341] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[11:24:12.341] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:24:12.412] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:24:13.077] INFO: Expecting 41600 events.
[11:24:17.114] INFO: 41600 events read in total (3510ms).
[11:24:17.119] INFO: Test took 4707ms.
[11:24:17.268] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:17.268] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 913531
[11:24:17.268] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[11:24:17.268] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:24:17.337] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:24:18.002] INFO: Expecting 41600 events.
[11:24:22.041] INFO: 41600 events read in total (3512ms).
[11:24:22.046] INFO: Test took 4709ms.
[11:24:22.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:22.195] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912583
[11:24:22.195] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[11:24:22.195] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:24:22.263] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:24:22.928] INFO: Expecting 41600 events.
[11:24:26.974] INFO: 41600 events read in total (3519ms).
[11:24:26.979] INFO: Test took 4716ms.
[11:24:27.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:27.125] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 913368
[11:24:27.125] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[11:24:27.126] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:24:27.194] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:24:27.860] INFO: Expecting 41600 events.
[11:24:31.922] INFO: 41600 events read in total (3536ms).
[11:24:31.928] INFO: Test took 4734ms.
[11:24:32.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:32.075] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 913250
[11:24:32.075] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[11:24:32.075] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:24:32.144] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:24:32.810] INFO: Expecting 41600 events.
[11:24:36.848] INFO: 41600 events read in total (3512ms).
[11:24:36.853] INFO: Test took 4709ms.
[11:24:37.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:37.002] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 914793
[11:24:37.002] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[11:24:37.002] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:24:37.071] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:24:37.736] INFO: Expecting 41600 events.
[11:24:41.777] INFO: 41600 events read in total (3514ms).
[11:24:41.782] INFO: Test took 4711ms.
[11:24:41.932] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:41.932] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 914842
[11:24:41.932] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[11:24:41.932] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:24:42.001] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:24:42.667] INFO: Expecting 41600 events.
[11:24:46.709] INFO: 41600 events read in total (3515ms).
[11:24:46.715] INFO: Test took 4714ms.
[11:24:46.863] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:46.863] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 914654
[11:24:46.863] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[11:24:46.864] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:24:46.932] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:24:47.597] INFO: Expecting 41600 events.
[11:24:51.639] INFO: 41600 events read in total (3515ms).
[11:24:51.645] INFO: Test took 4713ms.
[11:24:51.794] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:51.794] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 911812
[11:24:51.794] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[11:24:51.794] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:24:51.863] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:24:52.528] INFO: Expecting 41600 events.
[11:24:56.584] INFO: 41600 events read in total (3529ms).
[11:24:56.589] INFO: Test took 4726ms.
[11:24:56.737] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:24:56.737] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912929
[11:24:56.737] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[11:24:56.737] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:24:56.807] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:24:57.472] INFO: Expecting 41600 events.
[11:25:01.525] INFO: 41600 events read in total (3526ms).
[11:25:01.531] INFO: Test took 4724ms.
[11:25:01.680] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:01.681] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 913085
[11:25:01.681] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[11:25:01.681] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:01.750] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:02.415] INFO: Expecting 41600 events.
[11:25:06.453] INFO: 41600 events read in total (3511ms).
[11:25:06.459] INFO: Test took 4709ms.
[11:25:06.607] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:06.607] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912237
[11:25:06.607] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[11:25:06.608] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:06.677] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:07.342] INFO: Expecting 41600 events.
[11:25:11.385] INFO: 41600 events read in total (3516ms).
[11:25:11.391] INFO: Test took 4714ms.
[11:25:11.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:11.541] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 911427
[11:25:11.541] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[11:25:11.542] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:11.611] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:12.276] INFO: Expecting 41600 events.
[11:25:16.324] INFO: 41600 events read in total (3522ms).
[11:25:16.329] INFO: Test took 4719ms.
[11:25:16.478] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:16.478] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 912400
[11:25:16.478] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[11:25:16.478] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:16.547] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:17.212] INFO: Expecting 41600 events.
[11:25:21.200] INFO: 41600 events read in total (3461ms).
[11:25:21.205] INFO: Test took 4658ms.
[11:25:21.352] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:21.352] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 911808
[11:25:21.352] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[11:25:21.353] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:21.422] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:22.086] INFO: Expecting 41600 events.
[11:25:25.909] INFO: 41600 events read in total (3296ms).
[11:25:25.914] INFO: Test took 4492ms.
[11:25:26.060] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:26.060] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 901784
[11:25:26.060] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[11:25:26.060] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:26.349] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 165 eff = 0.998702
[11:25:26.349] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 153 eff = 0.999303
[11:25:26.350] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 168 eff = 0.997596
[11:25:26.350] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 160 eff = 0.996058
[11:25:26.350] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 148 eff = 0.99524
[11:25:26.350] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 175 eff = 0.995457
[11:25:26.351] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 170 eff = 0.995601
[11:25:26.351] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 152 eff = 0.996226
[11:25:26.351] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 177 eff = 0.994639
[11:25:26.351] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 177 eff = 0.994856
[11:25:26.351] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 141 eff = 0.994976
[11:25:26.351] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 159 eff = 0.995601
[11:25:26.352] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 163 eff = 0.995793
[11:25:26.352] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 148 eff = 0.997668
[11:25:26.352] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 148 eff = 0.998077
[11:25:26.352] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 130 eff = 0.998774
[11:25:30.046] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[11:25:30.046] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[11:25:30.046] INFO: ----------------------------------------------------------------------
[11:25:30.046] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:25:30.046] INFO: ----------------------------------------------------------------------
[11:25:30.047] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[11:25:30.047] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[11:25:30.047] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[11:25:30.047] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[11:25:30.047] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[11:25:30.047] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[11:25:30.047] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[11:25:30.047] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[11:25:30.047] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[11:25:30.047] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[11:25:30.047] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[11:25:30.047] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[11:25:30.064] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[11:25:30.736] INFO: Expecting 208000 events.
[11:25:48.122] INFO: 208000 events read in total (16859ms).
[11:25:48.143] INFO: Test took 18078ms.
[11:25:49.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:25:49.069] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4248234
[11:25:49.069] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[11:25:49.070] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[11:25:49.587] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 2 0 0 0 1 0 5 0
[11:25:49.587] INFO: number of red-efficiency pixels: 255 218 459 803 908 791 807 721 832 890 879 788 698 429 203 256
[11:25:49.587] INFO: number of X-ray hits detected: 160505 119617 202701 316406 332992 325956 334809 307754 332097 337432 333325 310527 302265 202283 119123 143890
[11:25:49.587] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:25:49.587] INFO: number of Vcal hits detected: 207737 207775 207475 207071 206919 207067 207050 207163 206910 206847 206939 207073 207164 207507 207542 207725
[11:25:49.587] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.5 99.6 99.6 99.6 99.6 99.5 99.5 99.6 99.7 99.8 99.9 99.9
[11:25:49.587] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.6 99.5 99.6 99.5 99.6 99.5 99.4 99.5 99.6 99.6 99.8 99.8 99.9
[11:25:49.587] INFO: X-ray hit rate [MHz/cm2]: 47.0 35.1 59.4 92.7 97.6 95.5 98.1 90.2 97.3 98.9 97.7 91.0 88.6 59.3 34.9 42.2
[11:25:49.587] INFO: PixTestHighRate::doXPixelAlive() done
[11:25:49.632] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[11:25:49.632] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[11:25:49.632] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[11:25:49.632] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[11:25:49.632] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[11:25:49.632] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[11:25:49.632] INFO: PixTest:: pg_setup set to default.
[11:26:40.262] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[11:26:40.263] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 384.3mA
[11:26:40.264] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 474.3mA
[11:26:40.264] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[11:26:40.264] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[11:26:40.264] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[11:26:40.264] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[11:26:40.264] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[11:26:40.264] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[11:26:40.264] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[11:26:40.264] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[11:26:40.264] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[11:26:40.264] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[11:26:40.264] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[11:26:40.346] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[11:26:40.347] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[11:26:40.356] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[11:26:40.356] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[11:26:40.356] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[11:26:40.356] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[11:26:40.356] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[11:26:40.356] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[11:26:40.360] QUIET: Connection to board 58 closed.
[11:26:40.440] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
MoReWeb-v0.5.1-904-gd94b9f2 on branch master