[17:07:26.223] INFO: *** Welcome to pxar ***
[17:07:26.223] INFO: *** Today: 2016/08/31
[17:07:26.248] INFO: *** Version: v1.9.0-818-g96727
[17:07:26.248] INFO: readRocDacs: data/mq807/dacParameters35_C0.dat .. data/mq807/dacParameters35_C15.dat
[17:07:26.249] INFO: readTbmDacs: data/mq807/tbmParameters_C0a.dat .. data/mq807/tbmParameters_C0b.dat
[17:07:26.249] INFO: readMaskFile: data/mq807/defaultMaskFile.dat
[17:07:26.249] INFO: readTrimFile: data/mq807/trimParameters35_C0.dat .. data/mq807/trimParameters35_C15.dat
[17:07:26.399] INFO: clk: 4
[17:07:26.399] INFO: ctr: 4
[17:07:26.399] INFO: sda: 19
[17:07:26.399] INFO: tin: 9
[17:07:26.399] INFO: level: 15
[17:07:26.399] INFO: triggerdelay: 0
[17:07:26.399] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[17:07:26.399] INFO: Log level: DEBUG
[17:07:26.410] QUIET: Connection to board DTB_WRPRHI opened.
[17:07:26.414] 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:
------------------------------------------------------
[17:07:26.416] INFO: RPC call hashes of host and DTB match: 398089610
[17:07:28.019] INFO: DUT info:
[17:07:28.019] INFO: The DUT currently contains the following objects:
[17:07:28.019] INFO: 2 TBM Cores tbm08c (2 ON)
[17:07:28.019] INFO: TBM Core alpha (0): 7 registers set
[17:07:28.019] INFO: TBM Core beta (1): 7 registers set
[17:07:28.019] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:07:28.019] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.019] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[17:07:28.020] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[17:07:28.021] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[17:07:28.022] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[17:07:28.023] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[17:07:28.023] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[17:07:28.023] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[17:07:28.023] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[17:07:28.023] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[17:07:28.023] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[17:07:28.023] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[17:07:28.023] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[17:07:28.023] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[17:07:28.023] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[17:07:28.025] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 30330880
[17:07:28.025] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xfbe0e0
[17:07:28.025] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xd60370
[17:07:28.025] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f187bf91010
[17:07:28.025] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f18821fc510
[17:07:28.025] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 30339072 fPxarMemory = 0x7f187bf91010
[17:07:28.026] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 383.5mA
[17:07:28.027] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.9mA
[17:07:28.027] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.8 C
[17:07:28.520] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x13acfc0
[17:07:28.581] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[17:07:28.582] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[17:07:28.582] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[17:07:28.587] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[17:07:28.587] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[17:07:28.587] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[17:07:28.600] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[17:07:28.600] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[17:07:28.620] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[17:07:28.620] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[17:07:28.632] INFO: PixTestCmd::init()
[17:07:28.638] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[17:07:28.638] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[17:07:28.638] INFO: readGainPedestalParameters data/mq807/phCalibrationFitErr35_C0.dat .. data/mq807/phCalibrationFitErr35_C15.dat
[17:07:28.884] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[17:07:28.884] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[17:07:28.898] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[17:07:28.905] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[17:07:28.905] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[17:07:28.906] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[17:07:28.928] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[17:07:28.928] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[17:07:28.928] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[17:07:28.928] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[17:07:28.928] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[17:07:28.935] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[17:07:28.935] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[17:07:28.956] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[17:07:28.956] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[17:07:28.956] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[17:07:28.956] INFO: readReadbackCal: data/mq807/readbackCal_C0.dat .. data/mq807/readbackCal_C15.dat
[17:07:28.965] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[17:07:28.973] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[17:07:28.974] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[17:07:28.974] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[17:07:28.974] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[17:07:28.993] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[17:07:28.993] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[17:07:37.328] INFO: ######################################################################
[17:07:37.328] INFO: PixTestAlive::doTest()
[17:07:37.328] INFO: ######################################################################
[17:07:37.331] INFO: ----------------------------------------------------------------------
[17:07:37.331] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:07:37.331] INFO: ----------------------------------------------------------------------
[17:07:37.334] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:37.714] INFO: Expecting 41600 events.
[17:07:42.126] INFO: 41600 events read in total (3694ms).
[17:07:42.274] INFO: Test took 4940ms.
[17:07:42.286] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:42.286] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[17:07:42.287] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[17:07:42.560] INFO: PixTestAlive::aliveTest() done
[17:07:42.560] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0 1
[17:07:42.560] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 19 17 42 75 58 53 70 57 65 59 62 47 57 31 20 22
[17:07:42.588] INFO: ----------------------------------------------------------------------
[17:07:42.588] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:07:42.588] INFO: ----------------------------------------------------------------------
[17:07:42.590] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:42.967] INFO: Expecting 41600 events.
[17:07:46.075] INFO: 41600 events read in total (2393ms).
[17:07:46.076] INFO: Test took 3486ms.
[17:07:46.076] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:46.076] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[17:07:46.076] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[17:07:46.076] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:07:46.496] INFO: PixTestAlive::maskTest() done
[17:07:46.496] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:07:46.519] INFO: ----------------------------------------------------------------------
[17:07:46.519] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:07:46.519] INFO: ----------------------------------------------------------------------
[17:07:46.521] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:07:46.897] INFO: Expecting 41600 events.
[17:07:49.708] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found
[17:07:49.708] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 8 ROCs were found
[17:07:49.708] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a01a 8000 43c8 c 228c 43c8 c 2286 43c8 c 2286 43c8 c 2282 43c8 c 228e 43ca c 2282 43c8 c 226e 43c8 c 2282 e022 c000
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a014 80b1 4388 c 268a 4388 c 268c 4388 c 268e 4388 c 266f 4388 c 268c 4389 c 2685 4388 c 266e 4388 c 2682 e022 c000
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a015 80c0 4388 c 228e 4388 c 2286 4388 c 2286 4388 c 226f 438a c 228e 4388 c 2282 4389 c 226e 4388 c 2283 e022 c000
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a016 8000 4388 c 228e 4388 c 2286 4388 c 2288 4388 c 226f 4388 c 228b 4388 c 2282 4389 c 226e 4388 c 2284 e022 c000
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a017 8040 4388 c 228e 4388 c 2287 4388 c 2285 4389 c 2281 4389 c 228e 4389 c 2282 4388 c 226e 43c9 c 2287 ffe 2220 ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a018 80b1 43c9 c 228c 43c9 c 2285 43c9 c 2288 43c9 c 2282 43c8 c 228b 43c8 c 2282 43c8 c 226a 43c9 c 2283 e022 c000
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a019 80c0 43c9 c 228e 43c9 c 2288 43c9 c 2285 43c8 c 2282 43c8 c 228c 43c8 c 2282 43c9 c 226e 43c8 c 2284 e022 c000
[17:07:49.708] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a11a 8000 43c8 c 2286 43c8 c 226f 43c8 c 226e 43c8 c 226c 43c8 c 228a 43c8 c 228a 43c8 c 226f 43c9 c 228a e022 c000
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a114 80b1 4388 c 266f 4389 c 2688 4388 c 2668 4388 c 266a 4388 c 2682 4388 c 268b 4388 c 266e 4388 c 2686 e022 c000
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a115 80c0 4388 c 2288 4388 c 226f 4388 c 226e 4388 c 226d 4388 c 228a 4388 c 228a 4389 c 2282 4388 c 228a e022 c000
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a116 8000 4388 c 228a 4388 c 226f 4388 c 226e 4388 c 226c 4388 c 228a 4388 c 228c 4389 c 2281 4389 c 2288 e022 c000
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a117 8040 4388 c 2286 438a c 226f 4388 c 226f 4388 c 226e 4388 c 228a 4388 c 228e 4388 c 2282 43c9 c 228b 3e 2220 ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a118 80b1 43c9 c 2286 43c9 c 226f 43c9 c 226e 43c9 c 226e 43c9 c 228a 43c9 c 228e 43c8 c 226f 43c8 c 228a e022 c000
[17:07:49.708] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a119 80c0 43c9 c 228a 43c8 c 226f 43c9 c 226e 43c9 c 226e 43c9 c 228b 43c9 c 228c 43c9 c 2280 43c8 c 228a e022 c000
[17:07:51.288] INFO: 41600 events read in total (3676ms).
[17:07:51.289] INFO: Test took 4768ms.
[17:07:51.300] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:07:51.300] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66558
[17:07:51.300] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[17:07:51.672] INFO: PixTestAlive::addressDecodingTest() done with 52 decoding errors
[17:07:51.672] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:07:51.672] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[17:08:31.861] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[17:08:31.861] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[17:08:31.861] INFO: ----------------------------------------------------------------------
[17:08:31.861] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:08:31.861] INFO: ----------------------------------------------------------------------
[17:08:32.000] INFO: Expecting 768 events.
[17:08:33.134] INFO: 768 events read in total (419ms).
[17:08:33.134] INFO: Test took 1267ms.
[17:08:33.140] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:08:33.938] INFO: Expecting 41600 events.
[17:08:37.577] INFO: 41600 events read in total (3112ms).
[17:08:37.582] INFO: Test took 4442ms.
[17:08:37.704] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:37.704] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 674705
[17:08:37.704] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[17:08:37.704] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:08:37.757] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:08:38.423] INFO: Expecting 41600 events.
[17:08:42.164] INFO: 41600 events read in total (3214ms).
[17:08:42.168] INFO: Test took 4411ms.
[17:08:42.284] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:42.284] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684259
[17:08:42.284] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[17:08:42.284] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:08:42.339] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:08:43.005] INFO: Expecting 41600 events.
[17:08:46.772] INFO: 41600 events read in total (3240ms).
[17:08:46.776] INFO: Test took 4437ms.
[17:08:46.934] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:46.934] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683847
[17:08:46.934] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[17:08:46.934] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:08:46.987] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:08:47.652] INFO: Expecting 41600 events.
[17:08:51.427] INFO: 41600 events read in total (3248ms).
[17:08:51.431] INFO: Test took 4444ms.
[17:08:51.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:51.546] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684349
[17:08:51.546] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[17:08:51.547] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:08:51.601] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:08:52.268] INFO: Expecting 41600 events.
[17:08:56.047] INFO: 41600 events read in total (3253ms).
[17:08:56.052] INFO: Test took 4451ms.
[17:08:56.169] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:08:56.169] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685960
[17:08:56.169] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[17:08:56.169] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:08:56.224] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:08:56.890] INFO: Expecting 41600 events.
[17:08:58.445] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[17:08:58.445] WARNING: Channel 1 ROC 4: Readback start marker after 4 readouts!
[17:08:58.445] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[17:08:58.445] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:08:58.445] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a046 8000 43c8 42 2266 613 20ae 43c8 42 226c 43c8 42 2281 43c8 42 226d 43c8 42 226e 110 286e 43c9 42 2288 43c8 11 284f 42 2281 702 246c 43c8 42 228d 359 2062 458 2848 e002 c000
[17:08:58.445] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a040 80b1 4388 42 266a 50c 224f 709 2466 4388 1a 2462 42 2680 4388 42 2669 4388 42 2682 715 286e 4389 42 2682 4388 4 284f 159 2866 4388 42 2682 153 204e 4389 42 26a4 e002 c000
[17:08:58.445] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a041 80c0 4388 42 2682 4388 42 266a 4388 42 2681 4389 42 266c 154 2663 4389 42 266f 114 286c 10c 2268 4388 42 266f db 20a2 4388 42 266a 4389 4d8 2041 e002 c000
[17:08:58.445] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a042 8000 4388 42 2682 d0 242a 353 286a 4d5 2a6e 4388 42 266f 4389 42 2686 4389 42 266e 144 2863 462 206e 4388 42 2684 4388 42 2668 8a 2282 c3 288a 6cd 2624 4388 42 2682 42 2a68 15a 204a 4389 42 26a6 e002 c000
[17:08:58.445] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a043 8040 4388 42 2686 210 2882 4389 42 2681 215 204c 71a 3a4c 53c8 23b 2f17 eff 2f30 2ae 2635 f05 20c2 53f 2ff8 42 2684 43c8 42 266f 112 28a9 248 266e 43c8 42 2666 689 2040 708 2865 858 2a4f 43c8 42 26a2 85 284a e002 c000
[17:08:58.445] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a044 80b1 43c8 42 266e 43c8 42 2682 408 2248 43c8 42 2685 43c8 42 266c d4 224e 43c8 42 2682 43c9 42 266f 853 2042 43c8 42 266f 43c8 42 268c e002 c000
[17:08:58.445] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a045 80c0 43c8 42 2282 8c 2a66 43c8 42 226d 43c8 42 226f 692 2066 43c8 a 2846 42 2280 101 266c 153 204f 6d4 2226 43cb 42 2284 51d 2462 720 228e 43c9 42 2285 2cc 2662 344 264e 359 2644 43c9 42 228a 6d0 2440 43c8 42 228f e002 c000
[17:08:58.445] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:08:58.446] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a146 8000 43c8 42 22a2 43c8 42 2264 311 2a68 752 204f 43c8 42 226a 318 2862 849 2062 43c8 61 2488 42 228e 20a 284f 65c 224c 810 2642 43c8 4c 246c 42 22ae 149 2440 43c8 42 226e 43c8 42 226f 43c8 42 2284 208 246f 25c 264c e002 c000
[17:08:58.446] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a140 80b1 4389 42 268f 311 2042 558 266e 4389 42 2666 4389 42 266e 4389 42 268e 2d1 2862 4389 42 268e 4389 42 2686 4388 42 266f 4389 42 268c 4a2 204b e002 c000
[17:08:58.446] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a141 80c0 4388 42 268e 213 264a 2a2 266a 855 2a4f 4388 42 264e 845 2a4e 4388 4388 42 26a5 292 2068 640 262e 4389 42 2686 4dd 2646 4389 42 2685 4388 42 266f 4389 42 2682 e002 c000
[17:08:58.446] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a142 8000 4388 13 2a82 42 268a cb 2a62 4388 42 2666 2a2 2862 44c 226e 660 2243 4389 42 266a 90 2240 410 2068 4cc 2061 55d 2063 4389 42 26a2 48c 2268 4388 18 204b 42 26a8 40d 266a 4388 42 2686 59 2662 2d8 244a 4388 42 266e 4388 42 2682 e002 c000
[17:08:58.446] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a143 8040 4388 42 268c 4388 42 2666 4388 42 268e 692 2a62 43c9 1042 267b e2a 3b55 87 2c0f 1e7a 20a7 43ca 203a 8 2042 66f 43c8 42 2684 e002 c000
[17:08:58.446] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a144 80b1 43c8 42 268e 43c8 12 2868 5d 244a 42 266f 43c8 42 266e 43c8 42 2688 50d 2266 694 2846 43c8 42 268c 69d 244e 43c8 42 2680 101 2a6a 158 262e 43c8 42 2683 43c8 42 266e 215 226e 35d 2262 e002 c000
[17:08:58.446] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a145 80c0 43c8 42 22a8 553 2262 553 266f 844 204e 43c8 111 244f 810 2266 43c8 42 2286 81a 224f 43c8 42 22a8 43c8 42 2284 151 2644 45c 2a4a 621 2488 43c8 9 204f 42 228a 294 284f 43c9 42 2285 43c8 42 2282 e002 c000
[17:09:00.675] INFO: 41600 events read in total (3258ms).
[17:09:00.680] INFO: Test took 4456ms.
[17:09:00.798] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:00.798] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685779
[17:09:00.798] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[17:09:00.798] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:00.853] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:01.520] INFO: Expecting 41600 events.
[17:09:05.314] INFO: 41600 events read in total (3267ms).
[17:09:05.318] INFO: Test took 4465ms.
[17:09:05.435] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:05.436] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684902
[17:09:05.436] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[17:09:05.436] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:05.490] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:06.157] INFO: Expecting 41600 events.
[17:09:09.951] INFO: 41600 events read in total (3267ms).
[17:09:09.955] INFO: Test took 4464ms.
[17:09:10.073] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:10.073] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685677
[17:09:10.073] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[17:09:10.073] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:10.128] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:10.794] INFO: Expecting 41600 events.
[17:09:14.602] INFO: 41600 events read in total (3281ms).
[17:09:14.607] INFO: Test took 4479ms.
[17:09:14.723] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:14.723] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684045
[17:09:14.723] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[17:09:14.724] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:14.779] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:15.446] INFO: Expecting 41600 events.
[17:09:19.257] INFO: 41600 events read in total (3285ms).
[17:09:19.261] INFO: Test took 4482ms.
[17:09:19.378] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:19.378] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684875
[17:09:19.378] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[17:09:19.378] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:19.434] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:20.100] INFO: Expecting 41600 events.
[17:09:23.893] INFO: 41600 events read in total (3266ms).
[17:09:23.897] INFO: Test took 4463ms.
[17:09:24.013] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:24.013] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685698
[17:09:24.013] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[17:09:24.014] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:24.067] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:24.733] INFO: Expecting 41600 events.
[17:09:28.508] INFO: 41600 events read in total (3249ms).
[17:09:28.512] INFO: Test took 4444ms.
[17:09:28.627] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:28.627] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685658
[17:09:28.627] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[17:09:28.628] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:28.681] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:29.347] INFO: Expecting 41600 events.
[17:09:33.154] INFO: 41600 events read in total (3280ms).
[17:09:33.159] INFO: Test took 4477ms.
[17:09:33.275] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:33.275] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685615
[17:09:33.275] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[17:09:33.275] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:33.329] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:33.995] INFO: Expecting 41600 events.
[17:09:37.773] INFO: 41600 events read in total (3252ms).
[17:09:37.778] INFO: Test took 4448ms.
[17:09:37.892] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:37.892] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685370
[17:09:37.892] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[17:09:37.893] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:37.946] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:38.611] INFO: Expecting 41600 events.
[17:09:42.399] INFO: 41600 events read in total (3261ms).
[17:09:42.403] INFO: Test took 4457ms.
[17:09:42.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:42.520] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 685540
[17:09:42.520] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[17:09:42.520] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:42.575] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:43.240] INFO: Expecting 41600 events.
[17:09:44.790] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 6 ROCs were found
[17:09:44.790] WARNING: Channel 1 ROC 5: Readback start marker after 13 readouts!
[17:09:44.791] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 7 ROCs were found
[17:09:44.791] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ef 8040 43cb 45 226e 43cb 45 2281 43ca 45 228e 451 2068 43c8 45 226c 28b 282a 43c8 45 226f 11d 2466 680 266f 6d2 206e 43c8 45 226c 641 2288 43c9 45 226c 43c8 45 228f 258 2843 519 2a62 e002 c000
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e9 80c0 4388 45 2684 41b 2261 4388 45 2682 418 262d 815 284f 4388 45 2680 554 2a44 812 266c 4389 45 2666 85 2246 4388 45 2682 50 2a46 219 2682 818 244e 4389 45 2682 64d 2a6a 4388 45 266e 614 262a 4388 45 26a3 e002 c000
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ea 8000 4389 45 2682 31c 2666 49a 2282 4389 45 2682 4388 45 2682 4388 45 2684 4388 45 2682 495 2462 55c 2263 609 206f 438a 45 2666 4388 45 266f 4389 45 268c 505 2261 e002 c000
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0eb 8040 4388 45 226e 400 2846 555 286a 4388 45 226f 4389 45 2286 4388 45 228f 4388 45 226f 4388 51 204a 45 224f 620 2483 4389 10 2022 45 2280 4388 45 228d e002 c000
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a0ec 80b1 4389 62 2844 45 2280 4388 45 226e 4a2 288f 4389 45 226f 2da 2442 4388 45 2282 4388 45 2284 248 2a68 4389 45 226a 284 3a6f ef7 3ee5 557 2ef0 2ef1 ef0 2e57 ff3 2577 37f 2f3a 27e 2b37 ffe 2020 ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ed 80c0 43c8 45 226c 158 224a 158 262e 443 228f 43c8 45 226e 43c8 45 2288 43c8 45 228c 24d 248b 35c 2862 64a 224f 43c8 45 226e 491 2a6a 43c8 45 2284 43c8 45 228a 603 2444 43c9 45 228e 14b 226a 691 2626 e002 c000
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ee 8000 43c9 45 226e 315 2a6e 43c8 45 2268 e0 2649 43c8 45 2283 43cb 45 2284 21d 224f 722 2a6a 43c9 9 204f 5b 2466 45 2286 8a 2a82 99 2288 60b 2a83 43c9 45 2265 350 2247 43c9 45 2286 59 2243 255 206a 31b 266b 349 2086 6e2 2446 712 2a4f 43ca 45 22a7 712 2646 85a 224f e002 c000
[17:09:44.791] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ef 8040 43ca 45 226f 43c8 45 226b 311 264f 43cb 45 2288 50a 2a2a 43cb 45 2286 d5 246b dc 2a4f e0 2a4f 608 2644 43cb 45 2286 2d2 2262 43ca 58 2444 4c 2422 45 228e 51b 208f 43c9 45 2282 758 2440 43c9 45 2286 e002 c000
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1e9 80c0 4388 45 268f 400 2a42 4388 45 266f 28a 2666 4388 45 268a 4388 45 268c da 2a6c 6cc 2a4a 815 224e 4388 45 268a 109 202c 288 2262 288 262e 861 284f 4388 45 2680 4389 45 266f 4389 45 268c e002 c000
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ea 8000 4388 45 268f 4388 45 2688 513 2866 4388 45 2682 4389 45 26a6 4cb 2282 4388 18 2668 45 26a2 418 2266 451 2245 562 2486 841 224b 4388 45 2682 4389 45 266f 4389 45 268a e002 c000
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1eb 8040 4389 45 2286 4389 45 2286 4d0 2a4e 4e2 268f 4389 45 2283 4388 45 22a2 4389 45 228a 4389 45 2281 4388 45 226e 85a 2a46 4389 45 228e e002 c000
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a1ec 80b1 4388 45 228f 649 2a2b 4388 45 2280 4388 45 228e ca 202e 4389 45 2282 491 286a 4389 45 2286 5a 2a6c 159 2848 149 206a 43cb 195c 3f61 403 3f60 aaa 2f5e f0a 2001 1f7d 2a87 ff5 253a 43c9 253f 3e 2020 ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ed 80c0 43c8 45 22aa 80d 244b 43c8 45 226a 21d 2268 43c9 45 2288 43c8 45 228f 412 2248 43c9 45 2282 84b 264e 43c9 45 2280 43c9 45 2282 43ca 45 228a 158 2642 281 2442 e002 c000
[17:09:44.791] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ee 8000 43c9 45 228a 490 242e 43c8 45 226f 43c8 45 226f 20c 2880 43c9 45 228f da 286f 4da 2a4b 43c9 45 2286 61d 2068 691 244a 43c9 45 226c 43c9 45 2282 51b 2266 71b 2a4a 43c9 45 226f e002 c000
[17:09:47.021] INFO: 41600 events read in total (3254ms).
[17:09:47.025] INFO: Test took 4450ms.
[17:09:47.141] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:47.141] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684784
[17:09:47.141] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[17:09:47.141] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:47.196] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:47.861] INFO: Expecting 41600 events.
[17:09:51.657] INFO: 41600 events read in total (3270ms).
[17:09:51.661] INFO: Test took 4465ms.
[17:09:51.777] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:51.777] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684369
[17:09:51.777] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[17:09:51.777] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:51.831] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:52.496] INFO: Expecting 41600 events.
[17:09:56.294] INFO: 41600 events read in total (3271ms).
[17:09:56.299] INFO: Test took 4468ms.
[17:09:56.413] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:09:56.413] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 683877
[17:09:56.413] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[17:09:56.414] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:09:56.468] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:09:57.133] INFO: Expecting 41600 events.
[17:10:00.892] INFO: 41600 events read in total (3233ms).
[17:10:00.896] INFO: Test took 4428ms.
[17:10:01.013] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:01.013] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 684941
[17:10:01.013] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[17:10:01.013] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:01.067] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:01.732] INFO: Expecting 41600 events.
[17:10:05.370] INFO: 41600 events read in total (3111ms).
[17:10:05.374] INFO: Test took 4307ms.
[17:10:05.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:05.488] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 680416
[17:10:05.488] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[17:10:05.488] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:05.763] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 171 eff = 0.999183
[17:10:05.763] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 167 eff = 0.999615
[17:10:05.764] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 149 eff = 0.99887
[17:10:05.764] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 164 eff = 0.997596
[17:10:05.764] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 144 eff = 0.997596
[17:10:05.764] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 142 eff = 0.997308
[17:10:05.764] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 168 eff = 0.998221
[17:10:05.764] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 153 eff = 0.998317
[17:10:05.764] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 149 eff = 0.998029
[17:10:05.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 160 eff = 0.99726
[17:10:05.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 155 eff = 0.997332
[17:10:05.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 171 eff = 0.997692
[17:10:05.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 159 eff = 0.997764
[17:10:05.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 147 eff = 0.998918
[17:10:05.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 163 eff = 0.999447
[17:10:05.765] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 135 eff = 0.999111
[17:10:18.605] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[17:10:18.605] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[17:10:18.605] INFO: ----------------------------------------------------------------------
[17:10:18.605] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:10:18.605] INFO: ----------------------------------------------------------------------
[17:10:18.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[17:10:18.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[17:10:18.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[17:10:18.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[17:10:18.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[17:10:18.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[17:10:18.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[17:10:18.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[17:10:18.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[17:10:18.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[17:10:18.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[17:10:18.605] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[17:10:18.624] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[17:10:19.295] INFO: Expecting 208000 events.
[17:10:34.453] INFO: 208000 events read in total (14631ms).
[17:10:34.469] INFO: Test took 15845ms.
[17:10:35.066] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:10:35.066] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3132524
[17:10:35.066] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[17:10:35.066] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[17:10:35.506] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0 1
[17:10:35.506] INFO: number of red-efficiency pixels: 181 124 262 457 505 508 486 401 403 571 509 491 448 277 136 170
[17:10:35.506] INFO: number of X-ray hits detected: 134869 89552 148293 227988 237176 239626 241411 217397 231662 251615 241991 229945 227933 145710 89424 111374
[17:10:35.506] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:10:35.506] INFO: number of Vcal hits detected: 207814 207871 207720 207516 207431 207438 207462 207573 207567 207325 207433 207464 207528 207701 207860 207769
[17:10:35.506] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.9 99.9 99.9
[17:10:35.506] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.7 99.8 99.8 99.7 99.7 99.7 99.8 99.9 99.9 99.9
[17:10:35.506] INFO: X-ray hit rate [MHz/cm2]: 39.5 26.2 43.5 66.8 69.5 70.2 70.8 63.7 67.9 73.8 70.9 67.4 66.8 42.7 26.2 32.6
[17:10:35.506] INFO: PixTestHighRate::doXPixelAlive() done
[17:10:35.551] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[17:10:35.551] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[17:10:35.551] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[17:10:35.551] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[17:10:35.551] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[17:10:35.551] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[17:10:35.552] INFO: PixTest:: pg_setup set to default.
[17:11:05.205] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[17:11:05.205] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 387.5mA
[17:11:05.206] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 468.7mA
[17:11:05.206] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[17:11:05.206] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[17:11:05.206] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[17:11:05.206] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[17:11:05.206] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[17:11:05.206] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[17:11:05.207] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[17:11:05.207] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[17:11:05.207] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[17:11:05.207] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[17:11:05.207] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[17:11:05.286] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[17:11:05.286] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[17:11:05.295] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[17:11:05.295] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[17:11:05.295] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[17:11:05.295] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[17:11:05.295] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[17:11:05.295] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[17:11:05.298] QUIET: Connection to board 58 closed.
[17:11:05.377] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries