[13:23:00.210] INFO: *** Welcome to pxar ***
[13:23:00.210] INFO: *** Today: 2016/09/08
[13:23:00.233] INFO: *** Version: v1.9.0-818-g96727
[13:23:00.234] INFO: readRocDacs: data/mq715/dacParameters35_C0.dat .. data/mq715/dacParameters35_C15.dat
[13:23:00.235] INFO: readTbmDacs: data/mq715/tbmParameters_C0a.dat .. data/mq715/tbmParameters_C0b.dat
[13:23:00.235] INFO: readMaskFile: data/mq715/defaultMaskFile.dat
[13:23:00.235] INFO: readTrimFile: data/mq715/trimParameters35_C0.dat .. data/mq715/trimParameters35_C15.dat
[13:23:00.384] INFO: clk: 4
[13:23:00.384] INFO: ctr: 4
[13:23:00.384] INFO: sda: 19
[13:23:00.384] INFO: tin: 9
[13:23:00.384] INFO: level: 15
[13:23:00.384] INFO: triggerdelay: 0
[13:23:00.384] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[13:23:00.384] INFO: Log level: DEBUG
[13:23:00.395] QUIET: Connection to board DTB_WRPRHI opened.
[13:23:00.399] 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:
------------------------------------------------------
[13:23:00.401] INFO: RPC call hashes of host and DTB match: 398089610
[13:23:02.005] INFO: DUT info:
[13:23:02.005] INFO: The DUT currently contains the following objects:
[13:23:02.005] INFO: 2 TBM Cores tbm08c (2 ON)
[13:23:02.005] INFO: TBM Core alpha (0): 7 registers set
[13:23:02.006] INFO: TBM Core beta (1): 7 registers set
[13:23:02.006] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:23:02.006] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:02.006] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[13:23:02.006] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:23:02.006] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:23:02.006] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[13:23:02.007] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[13:23:02.008] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[13:23:02.009] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:23:02.010] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:23:02.012] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 35311616
[13:23:02.012] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x12380e0
[13:23:02.012] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xfda370
[13:23:02.012] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fa615d94010
[13:23:02.012] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fa61bfff510
[13:23:02.012] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 35319808 fPxarMemory = 0x7fa615d94010
[13:23:02.013] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[13:23:02.015] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 465.5mA
[13:23:02.015] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[13:23:02.522] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1626b20
[13:23:02.591] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[13:23:02.591] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[13:23:02.591] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[13:23:02.597] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[13:23:02.597] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[13:23:02.597] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[13:23:02.609] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[13:23:02.609] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[13:23:02.634] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[13:23:02.634] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[13:23:02.664] INFO: PixTestCmd::init()
[13:23:02.674] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[13:23:02.674] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[13:23:02.695] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[13:23:02.702] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[13:23:02.702] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[13:23:02.702] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[13:23:02.702] INFO: readGainPedestalParameters data/mq715/phCalibrationFitErr35_C0.dat .. data/mq715/phCalibrationFitErr35_C15.dat
[13:23:02.958] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[13:23:02.958] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[13:23:02.958] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[13:23:02.958] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[13:23:02.958] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[13:23:02.965] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[13:23:02.965] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[13:23:02.981] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[13:23:02.981] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[13:23:02.981] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[13:23:02.981] INFO: readReadbackCal: data/mq715/readbackCal_C0.dat .. data/mq715/readbackCal_C15.dat
[13:23:02.999] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[13:23:03.007] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[13:23:03.007] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[13:23:03.007] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[13:23:03.007] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[13:23:03.020] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[13:23:03.021] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[13:23:14.911] INFO: ######################################################################
[13:23:14.911] INFO: PixTestAlive::doTest()
[13:23:14.911] INFO: ######################################################################
[13:23:14.915] INFO: ----------------------------------------------------------------------
[13:23:14.915] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:23:14.915] INFO: ----------------------------------------------------------------------
[13:23:14.917] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:23:15.298] INFO: Expecting 41600 events.
[13:23:19.691] INFO: 41600 events read in total (3674ms).
[13:23:19.837] INFO: Test took 4920ms.
[13:23:19.848] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:19.848] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[13:23:19.848] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[13:23:20.129] INFO: PixTestAlive::aliveTest() done
[13:23:20.129] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:23:20.129] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 30 33 55 70 73 78 82 74 74 84 102 92 80 40 26 31
[13:23:20.154] INFO: ----------------------------------------------------------------------
[13:23:20.154] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:23:20.154] INFO: ----------------------------------------------------------------------
[13:23:20.158] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:23:20.536] INFO: Expecting 41600 events.
[13:23:23.651] INFO: 41600 events read in total (2399ms).
[13:23:23.652] INFO: Test took 3494ms.
[13:23:23.652] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:23.652] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[13:23:23.652] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[13:23:23.653] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:23:24.074] INFO: PixTestAlive::maskTest() done
[13:23:24.074] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:23:24.097] INFO: ----------------------------------------------------------------------
[13:23:24.097] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:23:24.097] INFO: ----------------------------------------------------------------------
[13:23:24.099] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:23:24.477] INFO: Expecting 41600 events.
[13:23:28.867] INFO: 41600 events read in total (3675ms).
[13:23:28.869] INFO: Test took 4770ms.
[13:23:28.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:28.880] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66560
[13:23:28.880] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[13:23:29.250] INFO: PixTestAlive::addressDecodingTest() done
[13:23:29.250] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:23:29.250] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[13:23:57.836] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[13:23:57.836] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = AddressDecodingTest_C15_V0 -> AddressDecodingTest_mod
[13:24:03.732] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[13:24:03.732] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[13:24:03.732] INFO: ----------------------------------------------------------------------
[13:24:03.732] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:24:03.732] INFO: ----------------------------------------------------------------------
[13:24:03.871] INFO: Expecting 768 events.
[13:24:05.005] INFO: 768 events read in total (418ms).
[13:24:05.006] INFO: Test took 1268ms.
[13:24:05.012] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:05.809] INFO: Expecting 41600 events.
[13:24:09.738] INFO: 41600 events read in total (3402ms).
[13:24:09.743] INFO: Test took 4731ms.
[13:24:09.902] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:09.902] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 914368
[13:24:09.902] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[13:24:09.902] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:09.968] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:10.635] INFO: Expecting 41600 events.
[13:24:14.626] INFO: 41600 events read in total (3464ms).
[13:24:14.631] INFO: Test took 4663ms.
[13:24:14.781] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:14.781] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 918584
[13:24:14.782] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[13:24:14.782] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:14.850] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:15.516] INFO: Expecting 41600 events.
[13:24:19.522] INFO: 41600 events read in total (3480ms).
[13:24:19.527] INFO: Test took 4677ms.
[13:24:19.680] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:19.680] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 921137
[13:24:19.680] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[13:24:19.680] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:19.752] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:20.420] INFO: Expecting 41600 events.
[13:24:24.438] INFO: 41600 events read in total (3491ms).
[13:24:24.444] INFO: Test took 4692ms.
[13:24:24.592] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:24.592] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 920136
[13:24:24.592] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[13:24:24.592] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:24.661] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:25.327] INFO: Expecting 41600 events.
[13:24:27.440] WARNING: Channel 0 ROC 3: Readback start marker after 7 readouts!
[13:24:27.440] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e8 80b1 4e38 29d 2480 298 2441 4e38 29d 248e 4e38 261 264e 29d 24a5 549 2463 4e38 c2 2662 29d 24a8 715 2085 4e39 a2 244e 29d 24aa 492 2640 600 264b 4e38 29d 2485 460 2641 514 246f 848 224f 4e38 8b 2062 29d 24ad 4e39 1c 264f 20b 2a6a 29d 2488 292 2847 292 2440 e002 c000
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e2 8000 4e38 29d 286a 4c3 264f 4e38 9a 2a26 d9 2047 29d 28a5 491 244e 4e39 29d 288d 502 2847 841 2a4c 4e39 29d 2882 55a 2041 68c 2a40 6c8 204b 4e38 4a 2042 158 282f 29d 28a2 4e38 c9 224c 29d 288f 85a 2a6a 4e39 89 2846 29d 2883 4e39 29d 2886 e002 c000
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e3 8040 4e38 29d 286f 4e39 29d 288f 4e38 29d 288c 345 2089 4e38 99 224f 29d 28a0 4da 264c 4e38 29d 28a5 451 2a6a 48d 224c 4e38 8c 2229 249 206b 29d 2889 684 224f 4e38 8 284e 29d 288d 4e39 c5 2a62 351 2881 e002 c000
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e4 80b1 4e38 29d 2885 4e38 29d 288e 4e38 29d 288c 65b 2868 4e38 29d 28a9 4e38 29d 28a7 4e38 db 2664 29d 286a 292 242e 4e38 29d 28a4 4e38 29d 2887 709 2a6c e002 c000
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e5 80c0 4e38 29d 2480 410 2a46 4e38 ca 2846 29d 24a7 4e38 4e3a 24aa 4e38 29d 24ae 4e38 29d 24af 618 2242 4e38 119 284c 29d 2489 4e38 29d 2484 34b 2864 4e38 29d 248c e002 c000
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e6 8000 4e38 29d 2466 4e38 29d 24a2 4e38 29d 248d 410 2667 4e38 cd 2064 29d 24a9 4e38 29d 24a4 319 264c 69b 284a 4e39 29d 248c 4e38 240 264e 29d 248e 4e38 d2 204a e2 2269 29d 2485 e002 c000
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e7 8040 4e38 29d 246e 4e38 29d 24a3 4e38 29d 248f 711 284c 4e39 29d 24a9 4e38 29d 24a6 4e38 29d 248f 699 2461 4e38 211 2663 29d 2488 4e38 43 284c 204 2067 29d 24a4 362 206f e002 c000
[13:24:27.440] WARNING: Channel 0 ROC 3: Readback start marker after 9 readouts!
[13:24:27.440] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f1 80c0 4e38 29d 2080 4e38 29d 2083 615 2a65 4e38 221 2a63 29d 208e 35c 286c 4d4 264d 710 2a40 80c 2042 4e39 221 2867 29d 20a6 4e38 29d 208b 4e39 29d 2084 84c 246c 4e39 51 2644 29d 2083 4e39 29d 2085 441 2265 45b 2685 612 2a4f e002 c000
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0eb 8040 4e39 29d 2484 518 2062 4e39 29d 2489 4e39 29d 24a1 4e38 212 2445 29d 24c0 498 2246 4e39 29d 24ab 30a 284f 491 2a48 50a 2841 4e38 29d 248d 4e39 29d 248f 4e38 15 204d 10c 2642 29d 2482 e002 c000
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ec 80b1 4e39 29d 246e 4e39 218 262e 29d 24a5 4e38 29d 248c 4e39 29d 24c0 4dc 2666 4e39 db 2a4f 29d 24ad 4e39 29d 2482 4e39 29d 248e 4e38 711 284d e002 c000
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ed 80c0 4e38 29d 246a 4e39 19 2a6a c4 2a42 29d 248e 4e39 29d 24a7 4e39 21b 206e 20b 26a2 29d 24af 4d5 2a4f 4e39 29d 24a4 4e3b 5b 2466 24b 266f 29d 2489 449 262b 493 2684 4e38 29d 24a2 71d 264a 4e39 29d 2469 e002 c000
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ee 8000 4e38 155 2a28 29d 246f 71b 2666 4e39 29d 248d 4e39 29d 24a1 4e3b 29d 24a2 4e3a 29d 24a5 35d 2669 4e38 29d 248d 321 24a3 4e38 29d 248e 4e38 29d 24a0 2d2 2483 708 204f 844 2445 844 202e e002 c000
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ef 8040 4e3b 29d 206f 29d 2665 4e3b 29d 2088 4e3b 29d 208e 619 2449 85a 2468 4e38 29d 20a2 4e38 5 2284 160 2442 29d 20a9 282 2085 4e39 29d 2082 85c 2267 4e3b 29d 208c 4e3a 29d 2081 40d 2487 558 2665 e002 c000
[13:24:27.440] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f0 80b1 4e38 29d 206c 358 222f 49d 2668 4e38 29d 20a2 4e38 29d 208a 282 204a 419 266f 4e38 29d 20a8 85c 2661 4e39 20d 284c 29d 208f 4e38 3 242f 29d 20a3 4e38 29d 208e 302 2865 4e38 29d 206d 410 2a64 e002 c000
[13:24:29.378] INFO: 41600 events read in total (3525ms).
[13:24:29.383] INFO: Test took 4722ms.
[13:24:29.533] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:29.533] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 922296
[13:24:29.533] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[13:24:29.533] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:29.603] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:30.268] INFO: Expecting 41600 events.
[13:24:34.338] INFO: 41600 events read in total (3543ms).
[13:24:34.343] INFO: Test took 4740ms.
[13:24:34.498] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:34.498] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 921655
[13:24:34.498] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[13:24:34.498] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:34.570] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:35.237] INFO: Expecting 41600 events.
[13:24:39.300] INFO: 41600 events read in total (3536ms).
[13:24:39.306] INFO: Test took 4736ms.
[13:24:39.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:39.459] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 921171
[13:24:39.459] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[13:24:39.459] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:39.529] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:40.194] INFO: Expecting 41600 events.
[13:24:44.247] INFO: 41600 events read in total (3526ms).
[13:24:44.253] INFO: Test took 4724ms.
[13:24:44.403] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:44.403] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 919667
[13:24:44.403] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[13:24:44.404] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:44.474] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:45.139] INFO: Expecting 41600 events.
[13:24:49.205] INFO: 41600 events read in total (3539ms).
[13:24:49.211] INFO: Test took 4737ms.
[13:24:49.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:49.360] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 922761
[13:24:49.360] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[13:24:49.360] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:49.430] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:50.095] INFO: Expecting 41600 events.
[13:24:54.137] INFO: 41600 events read in total (3515ms).
[13:24:54.143] INFO: Test took 4713ms.
[13:24:54.294] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:54.294] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 920972
[13:24:54.294] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[13:24:54.294] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:54.364] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:55.029] INFO: Expecting 41600 events.
[13:24:59.090] INFO: 41600 events read in total (3535ms).
[13:24:59.095] INFO: Test took 4731ms.
[13:24:59.246] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:59.246] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 923599
[13:24:59.247] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[13:24:59.247] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:59.317] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:59.982] INFO: Expecting 41600 events.
[13:25:04.025] INFO: 41600 events read in total (3517ms).
[13:25:04.031] INFO: Test took 4714ms.
[13:25:04.184] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:04.185] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 923228
[13:25:04.185] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[13:25:04.185] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:04.257] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:04.926] INFO: Expecting 41600 events.
[13:25:08.960] INFO: 41600 events read in total (3507ms).
[13:25:08.966] INFO: Test took 4709ms.
[13:25:09.116] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:09.116] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 921847
[13:25:09.116] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[13:25:09.116] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:09.185] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:09.851] INFO: Expecting 41600 events.
[13:25:13.918] INFO: 41600 events read in total (3540ms).
[13:25:13.924] INFO: Test took 4739ms.
[13:25:14.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:14.075] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 922986
[13:25:14.075] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[13:25:14.075] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:14.145] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:14.810] INFO: Expecting 41600 events.
[13:25:16.953] WARNING: Channel 0 ROC 3: Readback start marker after 9 readouts!
[13:25:16.953] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:25:16.953] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fa 8000 4e38 285 286a 4e38 285 2889 4e38 285 288d 68c 242e 4e38 160 244e 151 244f 285 288d 650 202e 4e39 285 288c 544 2040 544 224d 4e38 285 286f 718 2848 4e38 9 2646 285 2884 84b 2649 4e39 285 2865 41a 2282 e002 c000
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f4 80b1 4e38 258 2642 288 2062 4e38 295 204d 288 2085 4e38 288 2089 2c5 224c 4e38 11 266a 100 2469 21b 2466 288 20aa 4c9 2669 714 242e 4e38 28b 286e 288 20a6 2cc 2867 2d8 2286 553 264c 4e38 288 2086 80a 244b 4e38 20b 2285 288 2082 459 2a4d 4e38 288 206f e002 c000
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f5 80c0 4e38 288 2062 659 246f 4e38 288 206f 4e38 288 2086 41c 206b 808 2a2f 4e38 288 208f 81c 244e 4e38 4a 284a 288 208f 4e38 288 2086 74b 2463 4e38 101 2a40 14a 2a43 288 2087 4e38 288 208a 692 284f e002 c000
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f6 8000 4e38 288 206a 4e38 15 2466 288 2088 4e38 288 206e 650 224c 4e38 a2 2865 288 208f 281 228f 620 264d 4e38 288 2089 44a 2048 4e39 288 206a 698 262f 4e38 20a 2860 288 2067 4c2 2465 84c 2828 4e38 288 2062 30d 2ae2 e002 c000
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f7 8040 4e38 285 2866 499 2265 499 2666 4e38 18 264f 285 2885 818 2649 4e38 4e3a 2646 41a 2868 41a 2a65 550 226f 4e39 285 288a 711 2842 4e39 21a 284f 285 28a3 4e38 285 286f 4e38 285 288e 4e38 285 286d 6dd 2868 e002 c000
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f8 80b1 4e39 285 286a 4e39 285 288a 4e39 285 288a 552 204f 4e38 285 2889 4e38 285 288e 70a 2666 849 2242 851 2663 4e39 a0 206f 285 288f 28c 2a6f 313 2264 313 2688 41c 2a62 4e39 285 288c 693 2868 758 2a2a 4e39 285 2868 613 224d e002 c000
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f9 80c0 4e38 285 286e 415 2662 819 226a 4e38 1b 206a 285 288f 411 2047 4e38 285 2885 4e39 285 2888 4e38 285 28a4 808 2a45 4e38 285 286a 4e38 10a 2a6f 285 28a1 50d 2042 6da 2847 4e38 99 2688 285 2868 4c4 2849 4c4 244d e002 c000
[13:25:16.954] WARNING: Channel 0 ROC 3: Readback start marker after 7 readouts!
[13:25:16.954] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a001 80c0 4e38 285 2484 4e38 285 248d 4e38 285 248f 4e39 285 2489 4e38 262 24a6 285 24ad 64a 266a 4e39 11c 244e 285 2483 4e39 285 2488 4c8 244e 844 2a4e 4e39 285 246f 284 2042 55d 2483 844 2a42 e002 c000
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fb 8040 4e39 285 286e 653 2a69 4e39 285 2886 6c3 264a 703 244e 843 2648 4e39 285 2888 4e38 285 288a 40c 244e 6c8 204d 4e39 285 288a 4e38 285 2881 74a 2a44 4e39 285 2888 488 228b 4ca 224a 540 284f 4e38 10a 244d 285 286b 6e2 2884 e002 c000
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fc 80b1 4e38 285 286a 4e39 285 2882 4e38 285 288e 4e39 285 288f 4e39 285 28aa 81c 2262 4e38 285 2881 4e38 285 286f 654 2449 4e38 285 284f 4d3 2aa3 50d 2448 50d 20a1 500 2a4f e002 c000
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fd 80c0 4e39 285 2864 601 242f 4e39 285 2882 4e39 285 288c 4cc 2a4d 685 2443 685 204b 4e38 21b 2862 285 28a4 698 2886 4e39 b 286a 285 28a9 4e3b 285 2888 4e39 285 2889 4e38 285 286c 648 204b 6e1 2a84 e002 c000
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fe 8000 4e38 285 2865 4e39 285 2882 4e38 285 288d 4e3b 285 288c 709 2a49 75b 266c 4e3a 285 2889 4e38 24b 2646 285 288f 2d3 246e 54d 206f 4e39 285 288a 308 2443 4e39 285 2862 355 2a6f e002 c000
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ff 8040 4e3b 285 2864 45c 2640 4e3a 285 2888 4d2 2245 804 286e 4e3a 285 288a 4e38 28a 2861 285 2885 29b 2a4f 4e38 5c 2042 285 28ab 4e39 1c 242f 285 2883 615 2a49 4e3a 285 2886 554 2649 4e3a 285 2866 55b 2665 e002 c000
[13:25:16.954] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a000 80b1 4e38 285 286a 4e38 285 2889 4e38 285 288e 4e38 20c 2a45 285 2889 44a 242e 4da 2069 4e39 285 288f 64a 2642 4e38 210 2445 285 2885 4e38 285 2885 4e38 10b 2a2c 251 2662 285 286f e002 c000
[13:25:18.877] INFO: 41600 events read in total (3540ms).
[13:25:18.883] INFO: Test took 4738ms.
[13:25:19.037] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:19.037] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 922341
[13:25:19.037] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[13:25:19.037] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:19.104] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:19.769] INFO: Expecting 41600 events.
[13:25:23.830] INFO: 41600 events read in total (3534ms).
[13:25:23.836] INFO: Test took 4732ms.
[13:25:23.985] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:23.985] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 921820
[13:25:23.985] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[13:25:23.985] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:24.054] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:24.719] INFO: Expecting 41600 events.
[13:25:28.799] INFO: 41600 events read in total (3553ms).
[13:25:28.804] INFO: Test took 4750ms.
[13:25:28.952] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:28.952] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 922075
[13:25:28.952] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[13:25:28.952] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:29.024] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:29.689] INFO: Expecting 41600 events.
[13:25:33.755] INFO: 41600 events read in total (3539ms).
[13:25:33.761] INFO: Test took 4737ms.
[13:25:33.909] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:33.909] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 921437
[13:25:33.909] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[13:25:33.910] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:33.980] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:34.645] INFO: Expecting 41600 events.
[13:25:38.657] INFO: 41600 events read in total (3485ms).
[13:25:38.663] INFO: Test took 4683ms.
[13:25:38.813] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:38.813] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 920197
[13:25:38.813] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[13:25:38.813] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:38.883] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:39.549] INFO: Expecting 41600 events.
[13:25:43.350] INFO: 41600 events read in total (3274ms).
[13:25:43.356] INFO: Test took 4473ms.
[13:25:43.505] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:43.505] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 910867
[13:25:43.505] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[13:25:43.506] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:43.796] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 155 eff = 0.99887
[13:25:43.796] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 164 eff = 0.999087
[13:25:43.796] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 154 eff = 0.996803
[13:25:43.796] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 171 eff = 0.995841
[13:25:43.797] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 161 eff = 0.995072
[13:25:43.797] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 170 eff = 0.995144
[13:25:43.797] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 156 eff = 0.994543
[13:25:43.797] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 171 eff = 0.995457
[13:25:43.797] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 146 eff = 0.995168
[13:25:43.797] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 152 eff = 0.993774
[13:25:43.798] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 186 eff = 0.993702
[13:25:43.798] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 165 eff = 0.995168
[13:25:43.798] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 152 eff = 0.995361
[13:25:43.798] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 152 eff = 0.99726
[13:25:43.798] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 170 eff = 0.999279
[13:25:43.798] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 144 eff = 0.99875
[13:26:08.156] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[13:26:08.156] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[13:26:08.156] INFO: ----------------------------------------------------------------------
[13:26:08.156] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:26:08.156] INFO: ----------------------------------------------------------------------
[13:26:08.157] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[13:26:08.157] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[13:26:08.157] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[13:26:08.157] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[13:26:08.157] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[13:26:08.157] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[13:26:08.157] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[13:26:08.157] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[13:26:08.157] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[13:26:08.157] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[13:26:08.157] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[13:26:08.157] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[13:26:08.175] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:26:08.845] INFO: Expecting 208000 events.
[13:26:26.117] INFO: 208000 events read in total (16745ms).
[13:26:26.140] INFO: Test took 17965ms.
[13:26:26.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:26.923] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4290841
[13:26:26.924] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[13:26:26.924] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:26:27.434] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:26:27.434] INFO: number of red-efficiency pixels: 243 179 529 785 885 906 951 838 908 1080 1025 809 891 456 198 239
[13:26:27.434] INFO: number of X-ray hits detected: 156388 117996 203349 309751 327108 329163 333648 309128 336151 346763 343374 315555 315269 207519 122132 150987
[13:26:27.434] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:26:27.434] INFO: number of Vcal hits detected: 207750 207806 207390 207094 206984 206938 206863 206998 206958 206629 206794 207036 206954 207494 207791 207749
[13:26:27.434] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.7 99.6 99.6 99.5 99.5 99.6 99.5 99.4 99.5 99.6 99.5 99.8 99.9 99.9
[13:26:27.434] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.6 99.5 99.5 99.5 99.5 99.5 99.3 99.4 99.5 99.5 99.8 99.9 99.9
[13:26:27.434] INFO: X-ray hit rate [MHz/cm2]: 45.8 34.6 59.6 90.8 95.9 96.5 97.8 90.6 98.5 101.6 100.6 92.5 92.4 60.8 35.8 44.3
[13:26:27.434] INFO: PixTestHighRate::doXPixelAlive() done
[13:26:27.480] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[13:26:27.480] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[13:26:27.480] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[13:26:27.480] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[13:26:27.480] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[13:26:27.480] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[13:26:27.480] INFO: PixTest:: pg_setup set to default.
[13:26:45.652] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[13:26:45.652] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C14_V0 -> highRate_xraymap_mod
[13:27:10.004] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[13:27:10.005] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 382.7mA
[13:27:10.006] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 466.3mA
[13:27:10.006] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.4 C
[13:27:10.006] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[13:27:10.006] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[13:27:10.006] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[13:27:10.006] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[13:27:10.006] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[13:27:10.006] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[13:27:10.006] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[13:27:10.006] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[13:27:10.006] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[13:27:10.106] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[13:27:10.106] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[13:27:10.120] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[13:27:10.120] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[13:27:10.120] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[13:27:10.120] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[13:27:10.120] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[13:27:10.120] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[13:27:10.123] QUIET: Connection to board 58 closed.
[13:27:10.202] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries