[13:23:56.436] INFO: *** Welcome to pxar ***
[13:23:56.436] INFO: *** Today: 2016/08/05
[13:23:56.459] INFO: *** Version: v1.9.0-816-gf0c0
[13:23:56.459] INFO: readRocDacs: data/mp112/dacParameters35_C0.dat .. data/mp112/dacParameters35_C15.dat
[13:23:56.460] INFO: readTbmDacs: data/mp112/tbmParameters_C0a.dat .. data/mp112/tbmParameters_C0b.dat
[13:23:56.460] INFO: readMaskFile: data/mp112/defaultMaskFile.dat
[13:23:56.460] INFO: readTrimFile: data/mp112/trimParameters35_C0.dat .. data/mp112/trimParameters35_C15.dat
[13:23:56.611] INFO: clk: 4
[13:23:56.611] INFO: ctr: 4
[13:23:56.611] INFO: sda: 19
[13:23:56.611] INFO: tin: 9
[13:23:56.611] INFO: level: 15
[13:23:56.611] INFO: triggerdelay: 0
[13:23:56.611] QUIET: Instanciating API for pxar v1.9.0+816~gf0c098f
[13:23:56.611] INFO: Log level: DEBUG
[13:23:56.622] QUIET: Connection to board DTB_WRPRHI opened.
[13:23:56.625] 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:56.628] INFO: RPC call hashes of host and DTB match: 398089610
[13:23:58.227] INFO: DUT info:
[13:23:58.227] INFO: The DUT currently contains the following objects:
[13:23:58.227] INFO: 2 TBM Cores tbm08c (2 ON)
[13:23:58.227] INFO: TBM Core alpha (0): 7 registers set
[13:23:58.227] INFO: TBM Core beta (1): 7 registers set
[13:23:58.227] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:23:58.227] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.227] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.227] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.227] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.227] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.228] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.228] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.228] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.228] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.228] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.228] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.228] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.228] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.228] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.228] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.228] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:23:58.228] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[13:23:58.229] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[13:23:58.230] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[13:23:58.233] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32743424
[13:23:58.233] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x12220e0
[13:23:58.233] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xfc4370
[13:23:58.233] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f6e79d94010
[13:23:58.233] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f6e7ffff510
[13:23:58.233] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32751616 fPxarMemory = 0x7f6e79d94010
[13:23:58.234] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 376.2mA
[13:23:58.235] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 473.5mA
[13:23:58.235] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -1.6 C
[13:23:58.723] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1610f80
[13:23:58.784] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[13:23:58.784] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[13:23:58.784] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[13:23:58.790] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[13:23:58.790] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[13:23:58.790] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[13:23:58.814] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[13:23:58.814] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[13:23:58.822] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[13:23:58.822] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[13:23:58.846] INFO: PixTestCmd::init()
[13:23:58.864] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[13:23:58.864] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[13:23:58.865] INFO: readGainPedestalParameters data/mp112/phCalibrationFitErr35_C0.dat .. data/mp112/phCalibrationFitErr35_C15.dat
[13:23:59.112] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[13:23:59.112] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[13:23:59.127] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[13:23:59.134] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[13:23:59.134] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[13:23:59.134] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[13:23:59.156] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[13:23:59.156] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[13:23:59.156] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[13:23:59.156] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[13:23:59.156] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[13:23:59.163] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[13:23:59.163] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[13:23:59.187] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[13:23:59.187] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[13:23:59.187] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[13:23:59.187] INFO: readReadbackCal: data/mp112/readbackCal_C0.dat .. data/mp112/readbackCal_C15.dat
[13:23:59.197] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[13:23:59.206] DEBUG: <PixTestTiming.cc/setParameter:L61> PixTestTiming::PixTest() targetclk = 4
[13:23:59.206] DEBUG: <PixTestTiming.cc/setParameter:L65> PixTestTiming::PixTest() ntrig = 10
[13:23:59.206] DEBUG: <PixTestTiming.cc/setParameter:L49> fNoTokenPass: 0
[13:23:59.206] DEBUG: <PixTestTiming.cc/init:L75> PixTestTiming::init()
[13:23:59.225] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[13:23:59.225] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[13:24:02.271] INFO: ######################################################################
[13:24:02.271] INFO: PixTestAlive::doTest()
[13:24:02.271] INFO: ######################################################################
[13:24:02.274] INFO: ----------------------------------------------------------------------
[13:24:02.274] 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:24:02.274] INFO: ----------------------------------------------------------------------
[13:24:02.276] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:02.652] INFO: Expecting 41600 events.
[13:24:06.904] INFO: 41600 events read in total (3534ms).
[13:24:07.051] INFO: Test took 4775ms.
[13:24:07.060] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:07.060] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[13:24:07.060] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[13:24:07.339] INFO: PixTestAlive::aliveTest() done
[13:24:07.340] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 2 0 0 0 0 0 0 0
[13:24:07.340] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 37 35 53 90 88 91 89 60 94 91 84 77 80 40 37 50
[13:24:07.362] INFO: ----------------------------------------------------------------------
[13:24:07.362] 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:24:07.362] INFO: ----------------------------------------------------------------------
[13:24:07.364] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:07.738] INFO: Expecting 41600 events.
[13:24:10.782] INFO: 41600 events read in total (2328ms).
[13:24:10.782] INFO: Test took 3418ms.
[13:24:10.782] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:10.782] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[13:24:10.782] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[13:24:10.782] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:24:11.205] INFO: PixTestAlive::maskTest() done
[13:24:11.206] 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:24:11.224] INFO: ----------------------------------------------------------------------
[13:24:11.224] 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:24:11.224] INFO: ----------------------------------------------------------------------
[13:24:11.227] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:11.601] INFO: Expecting 41600 events.
[13:24:15.854] INFO: 41600 events read in total (3537ms).
[13:24:15.855] INFO: Test took 4628ms.
[13:24:15.866] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:15.866] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66557
[13:24:15.866] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[13:24:16.237] INFO: PixTestAlive::addressDecodingTest() done
[13:24:16.237] 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:24:16.237] INFO: PixTestAlive::doTest() done, duration: 13 seconds
[13:24:23.763] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[13:24:23.763] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C3_V0 -> PixelAlive_mod
[13:24:26.987] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[13:24:26.987] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[13:24:26.987] INFO: ----------------------------------------------------------------------
[13:24:26.987] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:24:26.987] INFO: ----------------------------------------------------------------------
[13:24:27.126] INFO: Expecting 768 events.
[13:24:28.258] INFO: 768 events read in total (416ms).
[13:24:28.258] INFO: Test took 1265ms.
[13:24:28.265] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:29.063] INFO: Expecting 41600 events.
[13:24:32.826] INFO: 41600 events read in total (3237ms).
[13:24:32.832] INFO: Test took 4567ms.
[13:24:32.995] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:32.995] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 949221
[13:24:32.995] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[13:24:32.996] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:33.066] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:33.740] INFO: Expecting 41600 events.
[13:24:37.633] INFO: 41600 events read in total (3367ms).
[13:24:37.639] INFO: Test took 4573ms.
[13:24:37.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:37.793] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 955482
[13:24:37.793] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[13:24:37.793] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:37.864] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:38.530] INFO: Expecting 41600 events.
[13:24:42.535] INFO: 41600 events read in total (3478ms).
[13:24:42.541] INFO: Test took 4677ms.
[13:24:42.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:42.702] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 957372
[13:24:42.702] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[13:24:42.703] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:42.772] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:43.444] INFO: Expecting 41600 events.
[13:24:47.440] INFO: 41600 events read in total (3469ms).
[13:24:47.446] INFO: Test took 4674ms.
[13:24:47.611] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:47.611] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 957584
[13:24:47.611] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[13:24:47.611] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:47.680] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:48.347] INFO: Expecting 41600 events.
[13:24:52.362] INFO: 41600 events read in total (3489ms).
[13:24:52.368] INFO: Test took 4688ms.
[13:24:52.529] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:52.529] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 958801
[13:24:52.529] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[13:24:52.529] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:52.598] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:53.266] INFO: Expecting 41600 events.
[13:24:57.266] INFO: 41600 events read in total (3473ms).
[13:24:57.273] INFO: Test took 4675ms.
[13:24:57.434] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:24:57.434] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 956889
[13:24:57.434] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[13:24:57.434] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:24:57.503] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:24:58.169] INFO: Expecting 41600 events.
[13:24:59.767] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 4 ROCs were found
[13:24:59.767] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (3) != Token Chain Length (8)
[13:24:59.767] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (100) != TBM ID (239)
[13:24:59.767] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[13:24:59.767] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (240) != TBM ID (100)
[13:24:59.767] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a166 8000 43c8 93 20a8 299 224c 440 2460 509 2663 6d0 206f 43c8 93 208d 43c8 b 284e 93 2085 10a 264f 55a 2648 43c8 93 20a9 65d 284f 43c8 93 2081 218 2645 43c8 1c 202f 93 208f 2c1 2882 43c8 93 2086 43c8 93 20a0 98 2a68 215 2643 e002 c000
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a160 80b1 4389 93 20a5 2da 2286 700 2442 4389 20 2a86 93 2088 512 2a88 852 2280 4389 93 2082 28c 2425 28c 2042 314 2462 314 204f 4389 93 20ad 4389 93 2088 4e2 2287 4389 93 208c 442 2a49 6dd 2a4f 4389 93 2082 4389 93 20a5 254 282f 704 2026 741 2045 e002 c000
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a161 80c0 4388 93 20c6 4388 93 208e 88 2a65 94 2648 604 2040 603 286e 4388 93 208a 4388 93 20aa 21a 226f 419 2481 4389 93 2082 c9 2a48 68d 282f 68d 2449 4389 93 2089 341 264e 4389 93 2085 db 246d cc 224e 4389 93 208c e002 c000
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a162 8000 4388 93 20ae 110 2a4f 75b 2461 4388 93 2086 35a 224f 619 2048 619 2248 619 264a 4389 93 2069 809 224d 4389 93 208e 2d1 2645 4388 93 206e 88 2646 498 2884 84b 2268 4388 93 2088 4389 93 2082 714 282a 4389 93 208f e002 c000
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a163 8040 4388 93 20aa 4389 93 2082 8b 2083 203 2262 4388 1093 308f 40d 2a65 f60 2367 83 3c09 e81 3802 1fea 2a25 0 3efa 805 2409 ea0 43cb 5c7 2201 94c 43c8 93 208f e002 c000
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a164 80b1 43c8 49 2268 93 20ad 8a 2669 294 2481 448 2443 64a 2261 848 224e 43c8 93 20a0 605 2645 43c8 99 2829 93 208f 6c8 2665 43c8 93 20c6 92 2a4a 43c8 93 2083 261 2467 251 20c2 491 2840 75b 2a64 43c8 93 208a 258 2a27 51b 246d 43c8 93 208c 43c8 93 208f e002 c000
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a165 80c0 43c8 93 20ae 344 2862 4cc 2860 753 2a4f 862 2a69 43c8 1d 286c 93 20a3 4d0 2669 51b 2881 51b 2a81 558 242f 719 2a66 43c8 93 208d a2 2a68 51d 226e 43c8 93 20aa c5 264f c5 2a4f 653 2468 43c8 93 2086 43c8 93 2087 404 2240 518 224e 43c8 93 206f 2e0 2869 43c8 93 20a2 341 2846 341 2a4e 6d0 222b e002 c000
[13:24:59.767] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a067 8040 43c8 93 2080 242 2241 43c8 93 284e 93 208f 43c8 93 20a6 60c 282f 709 2a4e 43c9 9 2067 43c8 93 206c 495 2860 6e1 288a 43c8 93 2088 43c8 93 208f 843 2a2a 849 222d 849 2629 43c8 93 2085 84b 2885 e002 c000
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a062 8000 4388 93 2088 4388 93 208d 2d4 2662 498 2445 4389 93 20a3 4389 93 208f 451 2082 4388 93 208f 40c 284f 4d5 2865 65a 222d 71c 2a29 4388 93 208d 685 2a63 4389 14 2441 93 2089 45c 202f 6e0 2a65 749 222c 749 264e 4389 93 208e 121 2885 610 264d 65c 2461 e002 c000
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a063 8040 4388 93 2082 4389 93 2085 4388 93 20a8 4388 11 264e 93 208f 148f 27f5 7ed 3f3d 65f 2fce 17ff
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ef 90aa 1b91 35fd f97 2fff dff 2c2f 3cd 2b56 424 2c23 d44 2910 e002 c000
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a064 80b1 43c8 93 2086 43c8 93 2088 d9 2a43 221 204f 43c8 93 208a 608 282e 43c8 93 208e 43c8 93 2060 662 286b 43c8 93 20a5 43c8 93 2088 43c8 93 20a0 8a 2441 8a 262e 41d 244f 40a 2a29 509 2483 e002 c000
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a065 80c0 43c8 93 208c a1 2681 4c2 2860 43c8 93 208d 43c8 93 20a2 29d 264f 43c8 93 20a1 82 284f 620 2268 43c8 93 2085 859 2665 43c8 93 20a5 24c 2065 2cc 2864 2ca 204d 31d 28a6 43c8 93 2088 43c8 19 244d 93 20ae e002 c000
[13:24:59.767] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a066 8000 43c8 93 2086 43c8 93 20a0 43c8 93 20a2 24b 2262 43c8 21 204c 9d 284e 93 208c 89 2a4b 14b 286a 290 244f 298 2a4e 41d 2862 6c0 2448 43c8 93 208e 551 266c 43c8 3 2682 93 208b 50d 2882 43c8 20 2462 59 286f 93 20a4 362 2a8f 819 266f 43c8 93 20a6 e002 c000
[13:25:02.217] CRITICAL: <hal.cc/condenseTriggers:L1899> Data size does not correspond to 10 triggers! Aborting data processing!
[13:25:02.225] INFO: 0 events read in total (3529ms).
[13:25:02.225] CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L783> Incomplete DAQ data readout! Missing 4160 Events.
[13:25:02.226] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #1
[13:25:02.893] INFO: Expecting 41600 events.
[13:25:06.968] INFO: 41600 events read in total (3548ms).
[13:25:06.974] INFO: Test took 4748ms.
[13:25:07.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:07.140] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 957585
[13:25:07.140] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[13:25:07.140] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:07.211] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:07.880] INFO: Expecting 41600 events.
[13:25:11.862] INFO: 41600 events read in total (3456ms).
[13:25:11.869] INFO: Test took 4658ms.
[13:25:12.035] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:12.035] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 959099
[13:25:12.035] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[13:25:12.036] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:12.105] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:12.771] INFO: Expecting 41600 events.
[13:25:16.808] INFO: 41600 events read in total (3510ms).
[13:25:16.814] INFO: Test took 4709ms.
[13:25:16.974] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:16.974] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 959162
[13:25:16.974] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[13:25:16.975] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:17.045] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:17.711] INFO: Expecting 41600 events.
[13:25:21.719] INFO: 41600 events read in total (3481ms).
[13:25:21.726] INFO: Test took 4681ms.
[13:25:21.885] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:21.885] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 958761
[13:25:21.885] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[13:25:21.885] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:21.955] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:22.624] INFO: Expecting 41600 events.
[13:25:26.665] INFO: 41600 events read in total (3514ms).
[13:25:26.673] INFO: Test took 4718ms.
[13:25:26.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:26.841] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 961146
[13:25:26.841] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[13:25:26.841] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:26.911] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:27.578] INFO: Expecting 41600 events.
[13:25:29.047] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[13:25:29.047] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[13:25:29.047] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05c 80b1 43c9 10 228c 295 204a 43c9 10 2268 20a 2828 43c8 10 2288 59 2045 43c8 10 2066 10 228f 854 2a46 43c8 10 226d 351 2a45 43c8 10 2266 755 246d 43c9 10 2268 810 2a29 43c8 10 22a4 418 2829 e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a056 8000 4388 10 266e 4388 10 266b 4388 10 266d 522 2841 522 2a4c 552 2664 4388 10 268e 88 246c 641 2448 4388 1a 2062 10 266f 9c 2222 10c 2042 803 2a6d 4388 10 268a 4d0 2884 4d0 2a4d 4388 10 2665 30b 2888 4388 15 2066 10 26c0 5d 2265 e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a057 8040 4388 10 266f 11d 2686 360 2460 4388 10 266e 4388 10 2682 44b 2662 4389 10 2683 754 2643 80d 284a 4388 10 2686 255 244f 313 204f 748 2664 4388 10 268b 13 2269 4388 10 2661 351 206e 4388 10 26a2 20d 2440 e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a058 80b1 4389 10 2684 4389 10 266d 4389 10 2685 4388 8 284e 10 268f 4389 10 2680 454 266c 483 2066 4389 1c 2042 10 268d 2cb 204b 4389 10 2680 58 2040 4d0 2a23 4389 10 26a0 554 204f e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a059 80c0 4388 10 22ae 2cc 2041 4388 10 326e 462 3465 fc0 2f13 d57 3fff 2ff1 7ff 43c9 10 228d 43c8 10 2284 2c1 2242 34d 2a69 43c8 10 2284 411 2242 45b 2463 60d 266a 64d 244a 751 226f 758 2262 43c8 219 204c 44c 2240 4cc 2048 64d 2842 43c8 10 22a1 24c 224f 695 224d e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05a 8000 43c8 29c 2826 35c 284c 601 2842 43c8 10 226d 69c 2245 43c8 10 2286 602 2640 698 284f 85b 284f 43c8 10 228e 44a 2261 60a 284b 43c9 10 2289 9c 242f 15d 2a88 312 264d 43c8 10 2286 15b 248b 43c8 10 226e 4c 2842 308 2045 43c8 10 228e 841 2069 e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05b 8040 43c8 10 2288 45d 2267 554 2a4a 6d8 2a4b 43c8 10 226c 319 264f 43c9 10 226e 54d 2a64 74d 242f 853 2462 853 2a42 43c8 10 228c 6d5 2863 43c8 10 228c 43c9 1c 206a 10 228b 21d 244c 64b 246b 43c9 10 226d 14c 2281 288 280c 75a 2006 753 2848 43c9 10 2288 151 204e e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15c 80b1 43c8 10 2286 80b 222e 43c8 10 22a8 15a 2486 2cb 2469 43c8 10 2284 48c 2049 43c9 b 2668 10 228d 489 204f 43c8 10 2282 159 2244 159 262f 43c9 10 2281 43c8 10 226f 43c9 10 2268 e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a156 8000 4388 10 266e 4388 10 26a6 14d 286e 4388 10 2662 4388 10 2686 dc 222f 113 2262 15d 2266 809 2845 803 204f 4388 10 2680 59 2040 249 264c 48c 2a2c 858 2a4a 4388 10 2688 4388 10 2688 71b 244f 4388 10 266d e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a157 8040 4388 10 266e 48b 266d 4388 10 26a3 4388 10 266f dd 246c 4388 10 268f 60d 2847 713 2a4d 4388 10 2682 319 2286 4388 10 2683 319 2240 4388 10 266c 49c 2a4f 4389 10 266d e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a158 80b1 4389 10 266a 218 2a8e 491 2a80 6d2 284e 4389 10 2688 80b 224f 80b 264c 4389 10 2666 88 2840 88 2a42 319 2264 319 264f 41c 264d 702 2a4d 4389 10 268c 554 264f 4389 10 2689 4389 10 2689 55 2245 d1 2043 4389 10 2681 4388 10 2680 e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a159 80c0 4388 10 2282 4388 10 22a0 49 384f 11d 2861 2a 2fff 1c01 3fdf ae1 2050 b2a 43c8 10 228d 360 2665 490 266c 43c8 10 2288 748 2845 43c8 10 2280 43c8 10 2281 855 204e 43c9 10 2289 803 2a69 e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15a 8000 43c8 10 2268 20c 2080 291 244d 43c9 1d 206f 10 22a1 662 2262 43c8 10 2282 35c 288a 34a 2a80 41d 2463 801 224f 43c9 10 2286 344 226f 43c8 10 2282 105 246d 511 2040 43c8 10 226f 35b 286b 43c8 10 226f 43c8 10 226c e002 c000
[13:25:29.047] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15b 8040 43c9 10 2282 d9 2465 208 2686 43c8 1a 2865 10 22a4 43c9 10 2268 112 2a4f 850 2863 43c8 95 2448 458 2681 43c9 10 226f 218 2661 43c8 b 202f 10 228b 14a 2249 34b 2060 518 2261 43c8 10 2289 43c8 10 226d 410 2026 459 266f 688 2469 e002 c000
[13:25:31.584] INFO: 41600 events read in total (3479ms).
[13:25:31.590] INFO: Test took 4679ms.
[13:25:31.752] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:31.753] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 958716
[13:25:31.753] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[13:25:31.753] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:31.824] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:32.491] INFO: Expecting 41600 events.
[13:25:36.510] INFO: 41600 events read in total (3492ms).
[13:25:36.516] INFO: Test took 4692ms.
[13:25:36.688] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:36.688] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 957416
[13:25:36.688] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[13:25:36.689] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:36.760] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:37.429] INFO: Expecting 41600 events.
[13:25:38.917] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:25:38.917] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (7) != Token Chain Length (8)
[13:25:38.917] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a6 8000 43c8 1 2288 dc 2440 43c8 1 228a 43c8 1 2285 43c8 1 22a8 14c 2649 43c8 1 228f 30a 2845 43c8 1 22a8 653 2889 43c8 1 2286 34a 202f 349 282f 6ca 2447 43c8 1 22aa 448 2065 804 2840 e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a0 80b1 4388 1 268e 4c5 2466 4388 1 2687 449 2a46 4388 1 2689 548 2a66 4388 1 268f 90 2a82 d1 2466 160 286b 420 2081 55d 20a4 4388 1 2681 28a 204f 494 2264 555 2448 641 2449 4388 1 268a 58 284f 4388 1 2668 85 266a 113 2288 4388 1a 284e 1 268e 25b 2a80 e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a1 80c0 4388 1 2688 4388 1 2682 4388 1 2685 543 2868 4389 1 26a0 40b 2a6f 40c 2263 4389 18 2444 1 26a4 1b 22af 60c 224c 4389 1 268d 158 2a4f 761 2664 4389 1 2681 2d8 2a2a 40a 2262 64d 2248 64d 2625 4389 1 26a3 dd 2866 64d 2262 e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a2 8000 4388 1 268a 41d 2060 60a 264e 4388 1 2681 4389 1 266f 4389 1 26a2 4388 1 266f 15c 266f 511 204f 74a 2649 4388 1 26a3 68b 2281 4389 1 268b 4389 1 268f 41c 264c e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a3 8040 4388 1 226f 51b 224f 4389 1 2288 4388 1 226e 4388 1 22a5 43c8 1001 228f ab 2e7f ff9 2001 28f 24d3 46a 255c a8c 43c8 21 2862 1 228f 43c9 18 206a 1 22a5 4cb 2a62 e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a4 80b1 43c8 1 2288 95 282e 43c8 1 2282 43c8 1 228e 480 242f 43c8 1 22a1 43c8 1 228e 21d 2a68 658 224e 43c8 1 22a0 43c8 c 2045 1 228c 5a 204f 43c8 1 22a2 e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a5 80c0 43c8 1 2282 71a 226d 820 2062 43c8 1 2283 43c8 1 228c 4c9 242f 43c8 1 22a8 43c8 1 228e 25a 206f 259 284c 28d 264f 71c 242f 43c8 1 228c 43c8 1 226f 28c 2424 43c8 1 228f e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a6 8000 43c8 13 2848 1 226d 601 2042 68b 266b 81d 282d 43c8 1 22c9 43c8 1 22a2 120 2064 2c5 2a88 2c8 2248 40d 224e 43c8 1 2289 48b 206b 43c8 1 2288 254 2a25 294 284e 43c8 8 2841 1 2281 309 2029 43c8 1 226e 43c8 1 228c dd 242c e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a0 80b1 4389 1 2688 4389 1 26aa 90 2861 91 264f 6cb 2444 74b 2242 4389 1 266e 615 246d 615 2064 754 2a48 4389 1 268d 8 2a4f 6d9 2640 4389 1 268c 351 2266 6c3 2469 4389 1 2683 4389 1 266e 2e1 204f 80d 2861 4389 1 266f 441 2a68 e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a1 80c0 4388 1 26a0 4388 1 26a9 48c 2aa3 4cc 284f 4388 1 266f 488 2a64 4388 1 26a4 42 244f 150 244a 420 2a6f 4389 1 2680 218 2448 362 2266 4389 1 2689 215 264f 242 202e 419 224f 4389 1 2680 6d4 2281 4389 1 2683 31c 204f e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a2 8000 4388 1 2668 413 2a6a 608 2446 4388 1 26a4 c 2664 290 2442 289 2448 285 2aa2 360 2280 4389 1 2687 91 2a6a 4389 18 2446 14 2864 1 26ac 35b 2681 35b 2a4f 4388 0 2629 1 26a6 40 2445 253 2461 55a 2040 719 242f 4388 1 2681 455 2443 4389 1 266d 4389 1 268e 652 246e e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a3 8040 4388 1 226e 4389 1 228f 92 224d 4388 1 2284 4389 1 228e 43c8 1001 228a 10b 43c8 48 2009 348 2498 27a 2228 43c8 2220 9 234b 8ed 43c8 1 226f 159 244f e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a4 80b1 43c8 1 226a 43c8 1 22a2 8a 204f 43c8 1 22a2 44b 2488 4cd 244f 43c8 14 2842 1 2289 54c 284f 43c8 1 22a1 484 2047 43c8 8 2445 1 228d 4 2245 40d 2849 648 2a48 43c8 1 226d 43c8 1 228e 301 284e 4d9 2865 684 220a e002 c000
[13:25:38.917] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a5 80c0 43c8 1 226c 9a 2860 9a 2a64 43c8 1 22a8 154 284f 43c8 1 2284 12 2241 90 2040 249 244b 43c8 1 228a 43c8 1 2284 4d 2246 85c 2a4c 43c8 1 2281 43c8 1 226c 43c8 1 2285 e002 c000
[13:25:41.361] INFO: 41600 events read in total (3406ms).
[13:25:41.367] INFO: Test took 4606ms.
[13:25:41.530] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:41.530] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 956359
[13:25:41.530] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[13:25:41.530] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:41.603] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:42.271] INFO: Expecting 41600 events.
[13:25:46.210] INFO: 41600 events read in total (3412ms).
[13:25:46.216] INFO: Test took 4613ms.
[13:25:46.376] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:46.376] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 958741
[13:25:46.376] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[13:25:46.376] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:46.445] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:47.110] INFO: Expecting 41600 events.
[13:25:51.067] INFO: 41600 events read in total (3430ms).
[13:25:51.073] INFO: Test took 4628ms.
[13:25:51.235] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:51.235] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 958734
[13:25:51.235] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[13:25:51.235] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:51.305] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:51.970] INFO: Expecting 41600 events.
[13:25:55.904] INFO: 41600 events read in total (3407ms).
[13:25:55.910] INFO: Test took 4605ms.
[13:25:56.067] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:56.067] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 956774
[13:25:56.067] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[13:25:56.067] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:25:56.137] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:25:56.802] INFO: Expecting 41600 events.
[13:26:00.732] INFO: 41600 events read in total (3403ms).
[13:26:00.737] INFO: Test took 4600ms.
[13:26:00.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:00.897] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 956646
[13:26:00.897] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[13:26:00.897] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:26:00.969] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:26:01.637] INFO: Expecting 41600 events.
[13:26:05.522] INFO: 41600 events read in total (3358ms).
[13:26:05.529] INFO: Test took 4560ms.
[13:26:05.694] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:05.694] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 955611
[13:26:05.694] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[13:26:05.695] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:26:05.767] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:26:06.439] INFO: Expecting 41600 events.
[13:26:10.139] INFO: 41600 events read in total (3174ms).
[13:26:10.144] INFO: Test took 4377ms.
[13:26:10.299] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:10.299] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 945715
[13:26:10.299] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[13:26:10.300] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:26:10.591] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 151 eff = 0.998413
[13:26:10.591] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 155 eff = 0.998942
[13:26:10.592] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 166 eff = 0.997572
[13:26:10.592] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 177 eff = 0.995913
[13:26:10.592] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 181 eff = 0.994832
[13:26:10.592] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 163 eff = 0.994639
[13:26:10.592] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 171 eff = 0.993966
[13:26:10.592] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 155 eff = 0.995361
[13:26:10.592] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 157 eff = 0.994567
[13:26:10.593] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 177 eff = 0.992548
[13:26:10.593] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 159 eff = 0.993846
[13:26:10.593] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 184 eff = 0.994904
[13:26:10.593] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 160 eff = 0.995096
[13:26:10.593] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 155 eff = 0.997524
[13:26:10.593] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 144 eff = 0.99899
[13:26:10.593] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 164 eff = 0.998798
[13:26:18.435] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[13:26:18.435] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[13:26:18.435] INFO: ----------------------------------------------------------------------
[13:26:18.435] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:26:18.435] INFO: ----------------------------------------------------------------------
[13:26:18.436] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[13:26:18.436] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[13:26:18.436] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[13:26:18.436] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[13:26:18.436] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[13:26:18.436] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[13:26:18.436] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[13:26:18.436] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[13:26:18.436] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[13:26:18.436] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[13:26:18.436] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[13:26:18.436] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[13:26:18.457] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[13:26:19.124] INFO: Expecting 208000 events.
[13:26:36.142] INFO: 208000 events read in total (16491ms).
[13:26:36.166] INFO: Test took 17710ms.
[13:26:37.091] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:37.091] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 4463911
[13:26:37.091] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[13:26:37.092] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[13:26:37.612] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 2 1 0 0 0 0 0 0
[13:26:37.612] INFO: number of red-efficiency pixels: 291 199 474 784 941 896 1026 835 841 1158 970 897 848 442 184 306
[13:26:37.612] INFO: number of X-ray hits detected: 173412 122964 206995 312290 346118 347435 350774 318325 340350 375648 355614 334018 326726 203837 124345 158504
[13:26:37.612] 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:37.612] INFO: number of Vcal hits detected: 207692 207789 207451 207085 206864 206920 206692 207026 206896 206408 206822 206922 206983 207488 207798 207681
[13:26:37.612] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.6 99.5 99.5 99.4 99.6 99.5 99.3 99.5 99.5 99.6 99.8 99.9 99.8
[13:26:37.612] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.7 99.6 99.5 99.5 99.4 99.5 99.5 99.2 99.4 99.5 99.5 99.8 99.9 99.8
[13:26:37.612] INFO: X-ray hit rate [MHz/cm2]: 50.8 36.0 60.7 91.5 101.4 101.8 102.8 93.3 99.8 110.1 104.2 97.9 95.8 59.7 36.4 46.5
[13:26:37.612] INFO: PixTestHighRate::doXPixelAlive() done
[13:26:37.658] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[13:26:37.658] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[13:26:37.658] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[13:26:37.658] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[13:26:37.658] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[13:26:37.658] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[13:26:37.658] INFO: PixTest:: pg_setup set to default.
[13:26:45.011] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[13:26:45.012] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 379.4mA
[13:26:45.013] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 475.1mA
[13:26:45.013] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.8 C
[13:26:45.013] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[13:26:45.013] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[13:26:45.013] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[13:26:45.013] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[13:26:45.013] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[13:26:45.013] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[13:26:45.013] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[13:26:45.013] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[13:26:45.013] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[13:26:45.014] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[13:26:45.101] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[13:26:45.101] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[13:26:45.114] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[13:26:45.115] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[13:26:45.115] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[13:26:45.115] DEBUG: <PixTestTiming.cc/~PixTestTiming:L98> PixTestTiming dtor
[13:26:45.115] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[13:26:45.115] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[13:26:45.117] QUIET: Connection to board 58 closed.
[13:26:45.197] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries