[16:23:54.115] INFO: *** Welcome to pxar ***
[16:23:54.115] INFO: *** Today: 2016/09/29
[16:23:54.140] INFO: *** Version: v1.9.0-818-g96727
[16:23:54.140] INFO: readRocDacs: data/mq624/dacParameters35_C0.dat .. data/mq624/dacParameters35_C15.dat
[16:23:54.141] INFO: readTbmDacs: data/mq624/tbmParameters_C0a.dat .. data/mq624/tbmParameters_C0b.dat
[16:23:54.141] INFO: readMaskFile: data/mq624/defaultMaskFile.dat
[16:23:54.141] INFO: readTrimFile: data/mq624/trimParameters35_C0.dat .. data/mq624/trimParameters35_C15.dat
[16:23:54.292] INFO: clk: 4
[16:23:54.292] INFO: ctr: 4
[16:23:54.292] INFO: sda: 19
[16:23:54.292] INFO: tin: 9
[16:23:54.292] INFO: level: 15
[16:23:54.292] INFO: triggerdelay: 0
[16:23:54.292] QUIET: Instanciating API for pxar v1.9.0+818~g9672706
[16:23:54.292] INFO: Log level: DEBUG
[16:23:54.303] QUIET: Connection to board DTB_WRPRHI opened.
[16:23:54.306] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 58
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WRPRHI
MAC address: 40D85511803A
Hostname: pixelDTB058
Comment:
------------------------------------------------------
[16:23:54.309] INFO: RPC call hashes of host and DTB match: 398089610
[16:23:55.913] INFO: DUT info:
[16:23:55.913] INFO: The DUT currently contains the following objects:
[16:23:55.913] INFO: 2 TBM Cores tbm08c (2 ON)
[16:23:55.913] INFO: TBM Core alpha (0): 7 registers set
[16:23:55.913] INFO: TBM Core beta (1): 7 registers set
[16:23:55.913] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:23:55.913] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.913] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DAQ<-
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:23:55.914] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> trgnumber: 5
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 10
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> rundaqtrg: button
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> daqseconds: 5
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> rundaqseconds: button
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:23:55.915] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[16:23:55.916] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[16:23:55.917] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[16:23:55.919] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 32735232
[16:23:55.919] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xe290e0
[16:23:55.919] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xbcb370
[16:23:55.919] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7fac49d94010
[16:23:55.919] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7fac4ffff510
[16:23:55.919] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 32743424 fPxarMemory = 0x7fac49d94010
[16:23:55.920] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 379.4mA
[16:23:55.922] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 467.1mA
[16:23:55.922] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: -0.2 C
[16:23:56.417] DEBUG: <PixGui.cc/hvOn:L460> HV set On: 0x1218030
[16:23:56.481] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[16:23:56.481] DEBUG: <PixTestBBMap.cc/init:L79> PixTestBBMap::init()
[16:23:56.482] DEBUG: <PixTestBBMap.cc/PixTestBBMap:L27> PixTestBBMap ctor(PixSetup &a, string, TGTab *)
[16:23:56.487] DEBUG: <PixTestBB2Map.cc/setParameter:L58> setting fTargetIa = 24 mA/ROC
[16:23:56.487] DEBUG: <PixTestBB2Map.cc/init:L97> PixTestBB2Map::init()
[16:23:56.487] DEBUG: <PixTestBB2Map.cc/PixTestBB2Map:L29> PixTestBB2Map ctor(PixSetup &a, string, TGTab *)
[16:23:56.499] DEBUG: <PixTestBB3Map.cc/init:L81> PixTestBB3Map::init()
[16:23:56.499] DEBUG: <PixTestBB3Map.cc/PixTestBB3Map:L29> PixTestBB3Map ctor(PixSetup &a, string, TGTab *)
[16:23:56.517] DEBUG: <PixTestBB4Map.cc/init:L93> PixTestBB4Map::init()
[16:23:56.517] DEBUG: <PixTestBB4Map.cc/PixTestBB4Map:L26> PixTestMapeff ctor(PixSetup &a, string, TGTab *)
[16:23:56.531] INFO: PixTestCmd::init()
[16:23:56.550] DEBUG: <PixTestDaq.cc/init:L44> PixTestDaq::init()
[16:23:56.550] DEBUG: <PixTestDaq.cc/PixTestDaq:L22> PixTestDaq ctor(PixSetup &a, string, TGTab *)
[16:23:56.550] INFO: readGainPedestalParameters data/mq624/phCalibrationFitErr35_C0.dat .. data/mq624/phCalibrationFitErr35_C15.dat
[16:23:56.805] DEBUG: <PixTestDacDacScan.cc/init:L103> PixTestDacDacScan::init()
[16:23:56.805] DEBUG: <PixTestDacDacScan.cc/PixTestDacDacScan:L22> PixTestDacDacScan ctor(PixSetup &a, string, TGTab *)
[16:23:56.819] DEBUG: <PixTestGainPedestal.cc/setParameter:L83> PixTestGainPedestal::PixTest() fVcalStep = 10
[16:23:56.826] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[16:23:56.826] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[16:23:56.826] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[16:23:56.847] DEBUG: <PixTest.cc/setTestParameter:L637> setting ntrig to new value 10
[16:23:56.847] DEBUG: <PixTestPhOptimization.cc/setParameter:L37> setting fParNtrig ->10<- from sval = 10
[16:23:56.847] DEBUG: <PixTestPhOptimization.cc/setParameter:L42> setting fSafetyMarginLow ->20<- from sval = 20
[16:23:56.847] DEBUG: <PixTestPhOptimization.cc/setParameter:L48> setting fVcalMax ->100<- from sval = 100
[16:23:56.847] DEBUG: <PixTestPhOptimization.cc/setParameter:L53> setting fQuantMax ->0.98<- from sval = 0.98
[16:23:56.853] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[16:23:56.853] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[16:23:56.869] DEBUG: <PixTestReadback.cc/setParameter:L172> fCalwVd set to 1
[16:23:56.869] DEBUG: <PixTestReadback.cc/init:L95> PixTestReadback::init()
[16:23:56.869] DEBUG: <PixTestReadback.cc/PixTestReadback:L22> PixTestReadback ctor(PixSetup &a, string, TGTab *)
[16:23:56.869] INFO: readReadbackCal: data/mq624/readbackCal_C0.dat .. data/mq624/readbackCal_C15.dat
[16:23:56.879] DEBUG: <PixTestScurves.cc/setParameter:L93> set fOutputFilename =
[16:23:56.888] DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4
[16:23:56.888] DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10
[16:23:56.888] DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0
[16:23:56.888] DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()
[16:23:56.906] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[16:23:56.906] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[16:24:03.934] INFO: ######################################################################
[16:24:03.934] INFO: PixTestAlive::doTest()
[16:24:03.934] INFO: ######################################################################
[16:24:03.937] INFO: ----------------------------------------------------------------------
[16:24:03.937] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:24:03.937] INFO: ----------------------------------------------------------------------
[16:24:03.940] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:04.318] INFO: Expecting 41600 events.
[16:24:08.715] INFO: 41600 events read in total (3679ms).
[16:24:08.862] INFO: Test took 4922ms.
[16:24:08.875] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:08.875] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:24:08.875] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[16:24:09.148] INFO: PixTestAlive::aliveTest() done with 2 decoding errors
[16:24:09.148] INFO: number of dead pixels (per ROC): 0 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[16:24:09.148] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 42 33 38 55 52 51 60 51 61 61 44 48 63 35 18 20
[16:24:09.172] INFO: ----------------------------------------------------------------------
[16:24:09.172] INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:24:09.172] INFO: ----------------------------------------------------------------------
[16:24:09.174] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:09.552] INFO: Expecting 41600 events.
[16:24:12.619] INFO: 41600 events read in total (2352ms).
[16:24:12.619] INFO: Test took 3445ms.
[16:24:12.619] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:12.619] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 0
[16:24:12.619] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists MaskTest_C0 .. MaskTest_C15
[16:24:12.620] INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[16:24:13.041] INFO: PixTestAlive::maskTest() done
[16:24:13.042] INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:24:13.064] INFO: ----------------------------------------------------------------------
[16:24:13.064] INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:24:13.064] INFO: ----------------------------------------------------------------------
[16:24:13.066] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:13.444] INFO: Expecting 41600 events.
[16:24:17.828] INFO: 41600 events read in total (3669ms).
[16:24:17.830] INFO: Test took 4764ms.
[16:24:17.841] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:17.841] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66559
[16:24:17.841] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists AddressDecodingTest_C0 .. AddressDecodingTest_C15
[16:24:18.212] INFO: PixTestAlive::addressDecodingTest() done with 2 decoding errors
[16:24:18.212] INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:24:18.212] INFO: PixTestAlive::doTest() done, duration: 14 seconds
[16:24:27.763] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:24:27.763] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = PixelAlive_C1_V0 -> PixelAlive_mod
[16:24:30.235] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): caldelscan
[16:24:30.235] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[16:24:30.235] INFO: ----------------------------------------------------------------------
[16:24:30.235] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:24:30.235] INFO: ----------------------------------------------------------------------
[16:24:30.375] INFO: Expecting 768 events.
[16:24:31.507] INFO: 768 events read in total (417ms).
[16:24:31.508] INFO: Test took 1267ms.
[16:24:31.514] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:32.311] INFO: Expecting 41600 events.
[16:24:35.895] INFO: 41600 events read in total (3057ms).
[16:24:35.900] INFO: Test took 4386ms.
[16:24:36.012] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:36.012] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 624381
[16:24:36.012] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[16:24:36.013] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:24:36.061] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:36.727] INFO: Expecting 41600 events.
[16:24:40.423] INFO: 41600 events read in total (3170ms).
[16:24:40.428] INFO: Test took 4367ms.
[16:24:40.536] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:40.536] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 631289
[16:24:40.536] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[16:24:40.536] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:24:40.587] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:41.253] INFO: Expecting 41600 events.
[16:24:42.720] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 5 ROCs were found
[16:24:42.721] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 5 ROCs were found
[16:24:42.721] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02d 80c0 4389 11 2aa6 4388 11 2aa2 4389 11 2a6f 4388 11 2a6f 10b 2466 4388 0 2088 4388 11 2a8c 4389 11 2a85 4388 11 2a8a e002 c000
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a027 8040 4188 11 2a8f 315 2a60 4189 11 2a8e 98 226a 4188 11 2a83 4189 11 2a6d 6ca 286b 4189 0 208c 4188 11 2a8e 4a2 286e 4188 18 2882 11 2a88 545 246e 85b 2a40 418a 11 2a85 e002 c000
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a028 80b1 4188 11 2a8f 12 266a 4189 11 2a8c 4188 11 2a8b 850 2683 4188 4189 0 2083 4189 ca 202f 4188 11 2a89 4188 11 2a8f a2 2281 659 2042 e002 c000
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a029 80c0 4189 11 2ac0 4188 6c3 22c0 4189 11 2a81 4189 11 2a6f 4189 0 206f 105 2260 15b 2866 4189 11 2a6f 9b 2062 4189 11 2a6f 489 2a80 4189 11 2a8e 360 2662 494 202c e002 c000
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a02a 8000 4189 11 2aa4 554 266d 609 244f 4188 4189 11 2a87 4188 11 2a6e 244 244f 653 2a64 6d2 286f 538a 0 2088 515 25f7 ff1 25f6 d03 2dd6 a03 2543 dd7 2d7c f5 2556 1c11 3403 1d40 23ff ff0 3090 1d14 2056 13da 2540 fd5 2d85 111 203e 20 20ff 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
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02b 8040 4389 11 2a8e 755 2a6f 438a 4389 11 2a82 438b 810 286a 4388 0 2083 545 2269 4389 11 2a82 65d 2066 4388 11 2a8d 4389 11 2a6e 560 264e e002 c000
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02c 80b1 4389 11 2a8e 4388 11 2aa2 4388 11 2a88 814 284c 4388 11 2a83 4389 0 2085 694 2840 70c 262c 819 2648 4388 11 2a86 259 246a 4389 11 2a8a 349 2046 4388 11 2a84 28a 206e e002 c000
[16:24:42.721] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12d 80c0 4389 11 2aaa 4389 11 2a8f 99 2080 4388 11 2a8f 4389 11 2a8f 58 2846 740 244a 4389 11 2a6e 20d 2245 4388 c 2a42 11 2a8b 21 2282 299 268f 2dd 268f 4388 11 2a8a 4388 11 2a82 e002 c000
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a127 8040 4188 11 2a8a 4188 11 2aa9 2a2 2641 4188 4188 11 2a89 4188 11 2a85 2dc 2a40 859 2248 4188 11 2a8a 4188 11 2a8a 4188 11 2a6a 519 2062 e002 c000
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a128 80b1 4188 11 2aa4 349 284e 4188 11 2a8b 4188 11 2a82 4188 11 2a88 219 2062 64c 222e 4188 11 2a89 40b 286b 498 262f 4188 11 2a88 4188 11 2a8c 4e1 2a8b 4189 11 2a69 559 2a44 e002 c000
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a129 80c0 4189 11 2a8a e0 206b 750 222e 4189 11 2ac2 61d 2668 4189 11 2a8a 413 206c 68d 2a62 418b 11 2a6e 820 2482 4189 11 2a84 4189 11 2a8e 285 284a 418a 11 2a8c 4189 11 2a6f e002 c000
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a12a 8000 4189 11 2a82 4189 11 2a8f 61a 224f 4189 11 2a8a 255 2666 2c4 264e 4189 11 2a89 150 268a 5389 11 2a88 d5 26aa aa6 2aad 56 2aa9 4c2 2818 2a4 23e9 605 25a9 1a42 3bfe 1b95 2400 341f 19d5 25a9 1835 2815 8ba 2a50 888 257f 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
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12b 8040 4388 11 2a84 4389 11 2a8e d8 2a6e 2cb 244f 302 206b 4389 11 2a8d 4388 11 2a8c 4388 11 2a6f 653 286b 4388 11 2a6f 4389 11 2a8e 4389 11 2a6a 702 2842 e002 c000
[16:24:42.721] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a12c 80b1 4389 11 2a8f 489 2866 4388 71c 2a6c 4388 11 2a8e 343 226f 4389 1 2842 11 2aa2 4388 11 2a86 555 244f 541 244a 4389 11 2a87 4389 11 2a88 418 242f 4388 11 2a69 118 284f e002 c000
[16:24:44.987] INFO: 41600 events read in total (3208ms).
[16:24:44.991] INFO: Test took 4404ms.
[16:24:45.097] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:45.097] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 633332
[16:24:45.097] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[16:24:45.098] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:24:45.148] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:45.814] INFO: Expecting 41600 events.
[16:24:49.552] INFO: 41600 events read in total (3212ms).
[16:24:49.556] INFO: Test took 4408ms.
[16:24:49.665] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:49.665] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 633505
[16:24:49.665] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[16:24:49.665] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:24:49.716] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:50.382] INFO: Expecting 41600 events.
[16:24:54.107] INFO: 41600 events read in total (3199ms).
[16:24:54.112] INFO: Test took 4396ms.
[16:24:54.219] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:54.219] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 633674
[16:24:54.219] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[16:24:54.220] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:24:54.269] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:24:54.934] INFO: Expecting 41600 events.
[16:24:56.482] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 4 ROCs were found
[16:24:56.483] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (4) != Token Chain Length (8)
[16:24:56.483] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (89) != TBM ID (238)
[16:24:56.483] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (2) != Token Chain Length (8)
[16:24:56.483] ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (239) != TBM ID (89)
[16:24:56.483] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15b 8040 4389 55 248e 68b 226b 4388 220 202e 50d 2864 50d 246f 4389 55 248f 4388 4 2446 55 24a6 61a 2662 4388 55 2482 40b 224c 4389 55 2488 4389 55 2485 4388 55 24a2 e002 c000
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a155 80c0 4188 55 2882 453 226e 4188 55 28a2 a2 266e 218 202f 6d1 2260 4188 55 28a2 10b 2862 4188 55 288f 15c 242f 262 264e 262 2a45 4188 55 288f 4188 1 264f 55 288a 4188 55 2888 4188 55 28ad 65b 2262 65b 2648 e002 c000
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a156 8000 4188 55 2888 4188 55 2882 dc 286a 850 2a62 4188 55 288c 248 2a2e 4188 55 288d 35d 2a46 499 2669 4188 55 2883 751 244a 4188 55 288a 255 2462 4188 55 2882 4188 55 288e 4c8 204a e002 c000
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a157 8040 4188 55 28a2 4188 55 2881 74b 2848 4188 55 2888 99 264f 4188 55 28a2 342 2a42 713 2264 4188 55 288a 859 2244 4188 55 288a 4188 55 2888 61b 2085 4189 b 2464 55 288f 558 2446 e002 c000
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a158 80b1 4188 55 2884 258 264f 75a 2060 4189 55 288a 343 204f 4188 55 288a 104b 2668 186a 27da a62 3bd5 1659 20ab a00 2440 aa8 20a5 6b8 2055 883 4388 55 2883 4388 55 288e 2dc 262a e002 c000
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a159 80c0 4389 55 24a6 4388 55 24ab 80 2a4d 4389 55 248a 2c3 266a 438a 55 248e 4389 55 2484 51c 2042 4389 55 2488 438b 55 2488 4388 55 248a 860 204f e002 c000
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15a 8000 4389 55 24a2 4388 55 24a6 ca 284e 4389 55 248e 4389 55 248a 98 2a4e 4389 55 2486 81d 2482 4389 55 2482 4389 55 2487 4388 55 2482 e002 c000
[16:24:56.483] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05c 80b1 4388 55 248f 51 2082 801 224e 4388 55 248a 4389 55 24a2 4388 55 248a 343 20aa 659 204a 6a2 2881 4389 55 24af 4388 55 2482 2c8 242f 4388 55 24a2 14b 266a 15b 2242 2c8 284e 40c 264a 4388 55 248c e002 c000
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a057 8040 4188 55 28a8 4cd 2467 695 20a0 4188 55 2886 4c1 286d 4188 55 2888 858 264b 4189 55 286f 4188 55 28ae 71c 2a0c 4189 55 286f 55b 246a 4188 55 288e 418b 13 204e 55 2884 449 224d e002 c000
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a058 80b1 4189 55 28c9 4188 55 288a 4a 204f ca 22aa 4188 55 288e 4189 55 3882 20a 3055 905 2c03 1c15 35ac d00
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ee 8095 fbf 2e00 459 4388 55 28a9 4388 55 28a2 74a 2648 4388 0 2828 55 28a6 25d 2a88 e002 c000
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a059 80c0 4388 55 24a6 4388 55 2486 4389 55 248e 4389 55 248a 4389 55 24ae 4388 55 248e 48a 2440 4389 11 242e 55 24aa 45 246f 4389 55 248a e002 c000
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05a 8000 4388 55 24a2 4388 55 2488 4389 55 248a 54c 204a 4389 55 24aa 438b 55 24a2 854 2a2d 4388 55 2486 4389 55 24ae 4389 55 2484 451 2a4f e002 c000
[16:24:56.483] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05b 8040 4388 55 24a6 438b 55 248a 4389 55 248a 438a 55 2482 4388 55 24a2 cd 2482 4388 55 248e 458 2642 4389 55 24ab 704 2043 4389 55 24a2 5b 2668 e002 c000
[16:24:58.796] CRITICAL: <hal.cc/condenseTriggers:L1899> Data size does not correspond to 10 triggers! Aborting data processing!
[16:24:58.803] INFO: 0 events read in total (3301ms).
[16:24:58.803] CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L783> Incomplete DAQ data readout! Missing 4160 Events.
[16:24:58.803] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #1
[16:24:59.469] INFO: Expecting 41600 events.
[16:25:03.212] INFO: 41600 events read in total (3217ms).
[16:25:03.218] INFO: Test took 4415ms.
[16:25:03.324] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:03.324] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632021
[16:25:03.324] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[16:25:03.324] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:03.375] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:04.040] INFO: Expecting 41600 events.
[16:25:07.793] INFO: 41600 events read in total (3226ms).
[16:25:07.797] INFO: Test took 4422ms.
[16:25:07.904] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:07.904] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632845
[16:25:07.904] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[16:25:07.905] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:07.954] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:08.619] INFO: Expecting 41600 events.
[16:25:10.097] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[16:25:10.097] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a075 80c0 4388 d 26ae 4388 d 268f 4388 d 266f 4388 d 268f 51a 2665 651 224d 4389 0 2086 54c 2a4a 4388 d 268f 30a 2661 4388 d 266a 602 2a88 4389 d 2688 e002 c000
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06f 8040 418a d 2688 4189 d 268d 418b d 268c 4189 2 2a42 d 268d 358 2488 358 2068 4188 0 2085 29b 2662 520 2642 642 2848 4188 d 268f 418a d 2682 89 2848 92 2a48 822 244f 4188 d 2688 e002 c000
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a070 80b1 4188 d 268c 4188 d 268c 4188 d 2684 4188 d 266f 159 2265 50d 2848 4188 0 2087 4189 d 26a8 51b 2646 51b 2a4f 4188 d 268c 58 204e 55 286f 4189 9 2840 d 2688 20a 282f e002 c000
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a071 80c0 4188 d 268f 4188 d 2686 4188 d 2685 4188 d 268b 4188 0 206f 413 2a6b 4188 d 26a5 c1 284f 4189 d 266f 4188 d 268e dc 2a4f 4d1 266e e002 c000
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a072 9000 4388 a 2815 556 c00b
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a073 8040 4388 d 26a6 4388 d 26a2 4388 d 2687 4388 d 268f 4388 0 2089 52 286d 4388 d 268a 4388 d 2688 4388 d 2683 d 2a4a 748 2a29 e002 c000
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a074 80b1 4388 d 268f 4389 d 268f c1 2269 420 224a 4388 d 266a 4389 d 266f 15d 264e 315 248a 518 2442 4389 0 208d 71a 2026 4388 d 26a2 4388 d 2683 4389 d 2686 11a 2a62 e002 c000
[16:25:10.097] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a175 80c0 4388 d 2688 48d 266a 4388 d 268a 358 2a45 4388 d 2683 4388 d 2682 319 2043 4388 d 268d 355 2a6f 65a 2067 4388 4d 2443 4388 d 266f 4388 d 266f e002 c000
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16f 8040 418a d 268f 91 286f 40d 224a 745 2269 418a d 268a 418a d 2682 320 2068 4188 d 2685 418a d 2682 418a d 2683 15b 284e 71b 244f 4188 d 266f 4189 d 268a e002 c000
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a170 80b1 4189 d 2682 14a 2260 4189 d 268a 4189 d 268c 81c 284e 4188 d 2688 41c 2468 4189 d 2685 6c9 2048 4189 d 2686 2c8 2a62 2c9 2a83 4188 d 266f 519 206f 4189 d 2682 31a 2a62 e002 c000
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a171 80c0 4188 d 268f 295 2662 295 2a48 34c 204f 4188 d 26a9 4188 d 268a 4188 d 2685 140 2440 4189 d 2686 811 224f 4189 d 2686 4188 d 2684 2d2 2048 442 2268 4189 d 2681 e002 c000
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a172 9000 4388 0 22aa 828 20aa a82 244e 4388 19 2069 18 284a d 268f 14c 284f 4389 d 268a 251 2262 4389 d 268a 4388 d 2682 50 2246 4388 d 2689 4389 d 2682 2d8 2843 4389 d 266f 244 244f e002 c000
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a173 8040 4388 d 268a 4389 d 268a 9b 2862 849 2642 4388 d 2682 14c 264c 4388 d 2680 da 2465 4388 d 2685 4389 d 268a 4388 d 266f 4388 d 266f 31c 2644 e002 c000
[16:25:10.097] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a174 80b1 4388 d 268a 255 2a60 60a 224c 4388 d 2688 114 2468 4388 d 268a 4388 d 268c 551 2a2f 4388 d 2683 64a 204e 649 284a 4388 d 268a 4388 d 2683 755 2a63 4388 d 266f e002 c000
[16:25:12.391] INFO: 41600 events read in total (3245ms).
[16:25:12.395] INFO: Test took 4441ms.
[16:25:12.503] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:12.503] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 634691
[16:25:12.503] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[16:25:12.504] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:12.553] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:13.218] INFO: Expecting 41600 events.
[16:25:16.991] INFO: 41600 events read in total (3246ms).
[16:25:16.995] INFO: Test took 4442ms.
[16:25:17.104] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:17.104] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 634085
[16:25:17.104] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[16:25:17.104] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:17.154] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:17.820] INFO: Expecting 41600 events.
[16:25:19.364] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found
[16:25:19.364] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 6 ROCs were found
[16:25:19.364] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (130) != TBM ID (2)
[16:25:19.364] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (1) != Token Chain Length (8)
[16:25:19.364] ERROR: <datapipe.cc/CheckEventID:L463> Channel 1 Event ID mismatch: local ID (3) != TBM ID (130)
[16:25:19.364] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a084 80b1 4388 5b 26aa 158 2a4a 159 224b 4389 5b 268d 94 246e 112 2682 359 2a68 4388 5b 26a2 401 2088 4389 5b 26a7 604 244f 4388 5b 26c6 251 2241 4388 4c 204c 5b 26c1 4388 13 204f 5b 26c2 4388 5b 26a1 e002 c000
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07e 8000 4189 5b 2aab 4188 5b 2a89 4189 5b 2aaa 4189 5b 2a8d 70a 2468 4188 5b 2aad 709 2646 418a 5b 2a8f 255 2080 4189 5b 2aaa 612 2a66 4188 5b 2aa8 e002 c000
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07f 8040 418b 5b 2ac8 220 204a 21d 2888 4189 b 204f a 284a 5b 2a8f 418b 5b 2aa6 4189 5b 2aa4 40a 2a4e 4188 5b 2aae 4188 5b 2ac0 74a 2485 418a 5b 2a8f 4188 5b 2aac 112 2a82 e002 c000
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a080 80b1 4188 5b 2aab 4188 14 2440 5b 2aae 4188 5b 2aa9 551 264f 4188 5b 2aaf 859 264a 859 2a4a 4188 5b 2ac6 292 206a 41c 2262 4189 5b 2aa4 50a 284f 718 2a29 4188 5b 2aa8 4c9 262e 618 202e 800 264a 853 2268 4189 5b 2aa3 e002 c000
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a081 80c0 4188 5b 26a4 503 284f 4188 5b 26ac 4188 5b 26a3 4188 5b 26a9 4188 5b 26c2 4188 4189 5b 26af 9d 2a6e 218 262c 490 2a48 5388 5b 268e 7ff 2f75 540 2210 f 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
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a082 8000 4388 5b 26ae 60c 2048 4388 5b 268b 4389 5b 26a8 15c 284f 441 2245 443 2266 4388 5b 26a2 4388 5b 26c2 4389 5b 268f 40b 2a46 4389 5b 26af 4388 5b 26a6 e002 c000
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a083 8040 4388 5b 26ac 313 2488 4388 5b 26a3 4388 5b 268f 4388 5b 268f d8 2a61 10b 2849 4389 5b 26c3 15d 2a42 21d 2864 748 2445 849 244a 4388 5b 26aa 4388 5b 26c2 4389 5b 268f e002 c000
[16:25:19.364] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a185 80c0 4388 5b 268e 4388 5b 268f 219 2a48 4388 5b 2689 4388 5b 26ac 84 206a 4388 5b 26a2 55c 282a 4388 5b 268e 4388 5b 268d 4388 5b 2688 e002 c000
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a180 80b1 4189 5b 2aa2 4189 5b 2aa5 4189 5b 2aa4 21d 2a6a 54d 2448 4188 5b 2ac4 15c 204a 213 2a4f 68a 2461 4189 5b 2a8e 4189 5b 2a8f 160 244f 719 2886 4188 5b 2a8e 159 2268 455 2468 759 264c 4189 5b 2a8e e002 c000
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a181 80c0 4188 5b 268e 4188 5b 268a 4188 5b 268f 4188 12 2828 54 2068 5b 26ad 4189 5b 26a5 240 2663 644 2bef 64c 27ef 694 204f 168c 244a 4389 5a 2ace a4f
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a102 80db 4389 20d0 2db e002 c000
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a182 8000 4388 5b 268f 758 224f 4388 12 2244 12 264a 5b 268f 4389 5b 26ac 210 2880 4389 5b 26a5 c1 246c 819 2048 851 2888 4388 5b 26a0 4388 5b 268f 4389 5b 268f 4389 5b 268f e002 c000
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a183 8040 4388 5b 26a2 4389 5b 268f 71a 2262 4388 5b 26a2 682 2244 4388 5b 268e 548 2042 748 266a 4388 5b 268d 4389 5b 268f 4388 5b 26a5 4388 5b 268f e002 c000
[16:25:19.364] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a184 80b1 4388 5b 26ae 4388 5b 268e 759 204e 4388 5b 268e 685 220f 850 2a62 4388 5b 268f 2dc 266c 4388 5b 268a d2 224e 4388 5b 2684 10c 2228 2a2 2482 408 2462 4388 5b 26a1 4388 5b 268a e002 c000
[16:25:21.663] CRITICAL: <hal.cc/condenseTriggers:L1899> Data size does not correspond to 10 triggers! Aborting data processing!
[16:25:21.670] INFO: 0 events read in total (3323ms).
[16:25:21.670] CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L783> Incomplete DAQ data readout! Missing 4160 Events.
[16:25:21.670] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #1
[16:25:22.336] INFO: Expecting 41600 events.
[16:25:26.077] INFO: 41600 events read in total (3214ms).
[16:25:26.081] INFO: Test took 4411ms.
[16:25:26.190] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:26.190] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 631967
[16:25:26.190] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[16:25:26.190] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:26.240] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:26.905] INFO: Expecting 41600 events.
[16:25:30.674] INFO: 41600 events read in total (3242ms).
[16:25:30.678] INFO: Test took 4438ms.
[16:25:30.785] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:30.785] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632558
[16:25:30.785] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[16:25:30.785] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:30.835] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:31.500] INFO: Expecting 41600 events.
[16:25:32.918] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (7) != Token Chain Length (8)
[16:25:32.918] WARNING: Channel 1 ROC 7: Readback start marker after 3 readouts!
[16:25:32.919] ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 8 ROCs were found
[16:25:32.919] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a004 80b1 4388 18 248f 4389 18 248f 4388 18 2482 4c8 244b 4389 18 2468 4388 0 2082 85a 2a2e 4388 18 246e 749 2463 4388 18 2482 12 2440 54a 2882 4389 18 2482 684 2048 683 286e e002 c000
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fe 8000 4188 18 248f 12 208b 854 2483 4188 18 248e 4188 18 246f 4189 18 246f 259 2665 4dd 2662 4188 0 206f 418a 18 246c 6cd 224e 4188 18 246e 64a 2862 852 2224 4188 18 2488 e002 c000
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ff 8040 418b 18 2488 4189 18 248a 418b 18 246f 504 2285 4189 18 246a 114 2667 2c4 2263 4188 0 208c 4188 18 2468 215 2247 418a 18 246e 8d 286a 4188 18 246c e002 c000
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a000 80b1 4188 18 2488 4188 18 24a4 15 2865 4188 18 246f 1 244e 490 264f 4d5 264f 801 224a 841 2042 4188 18 246a 340 2a6f 64c 284a 4188 0 206f 4189 18 2468 50a 284f 4188 18 246e 4189 18 2484 e002 c000
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a001 80c0 4188 18 24a2 4188 20 2462 18 248e c9 2a4f 515 2a48 4188 18 246f 4188 18 246f 30c 2645 4188 0 208a 842 2028 4188 18 246e 12c2 2442 12c2 304a b71 2fff 2ff1 c09 2018 46a 209b 84a 2612 a40 4388 18 2466 e002 c000
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a002 8000 4388 18 24aa 4388 18 24a2 e0 206c 341 206c 4389 18 246e 4388 18 2462 41 2046 40 2843 4388 0 2080 c5 268f 4389 18 2488 4389 18 246c 19 2264 4388 18 246f e002 c000
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a003 8040 4388 18 248a 14 2068 4388 18 2486 4388 18 246f 50d 26a4 4388 18 2465 4389 0 2080 299 226f 4388 18 246a 403 224a 68c 2043 4388 18 246e 698 2846 4389 18 246e e002 c000
[16:25:32.919] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a104 80b1 4388 18 2484 4388 18 248a 4388 18 246f 245 2a4e 660 2668 4388 18 2488 4388 18 246e 54 284e 611 2a66 4388 18 246f 4388 18 246d 4388 18 2468 e002 c000
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fe 8000 4189 18 2486 9 2660 9 2a46 4c3 2468 70b 244f 4189 18 248a d1 2a4c 4189 18 2483 750 224d 4188 18 248a 4188 18 2466 342 284f 560 246a 4188 18 2486 4188 18 246a 418b 18 2469 511 2265 e002 c000
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ff 8040 418b 18 2488 611 2448 803 248a 418a 18 2482 418b 18 2482 75d 2844 4188 18 246f 418a 18 2462 418a 18 248a 512 246a 4188 18 246a 4189 18 2466 e002 c000
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a100 80b1 4189 18 2482 805 2665 4189 18 246c 9 2868 144 20a8 4189 18 2488 4188 18 246f 692 2649 4189 18 2469 214 2a80 302 2242 6d2 264f 6d2 2a42 4189 18 2488 4188 18 2468 719 264f 4189 18 246c 103 204d e002 c000
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a101 80c0 4188 18 2482 4188 18 2483 4188 19 2448 18 248c 261 224c 511 244f 4188 18 2486 4e0 2a6f 4189 18 2482 322 248f 4189 18 248f 5388 18 346f 538a 8aa 20aa a10 20ae bff 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
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a102 8000 4388 18 246f 4388 18 246e 255 2a45 4389 18 2488 4389 18 2484 30d 2a4f 4388 18 2480 4388 18 246e 4389 18 246e 4389 18 246a 5c 22aa e002 c000
[16:25:32.919] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a103 8040 4388 18 246e 4389 18 2488 4388 18 2484 4388 18 2484 710 2a28 4388 18 246f 15b 246e 2d4 2a65 75c 2a45 4389 18 2486 4388 18 246c 721 2a65 4388 18 246f e002 c000
[16:25:35.257] INFO: 41600 events read in total (3231ms).
[16:25:35.261] INFO: Test took 4426ms.
[16:25:35.369] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:35.370] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 631797
[16:25:35.370] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[16:25:35.370] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:35.419] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:36.084] INFO: Expecting 41600 events.
[16:25:39.848] INFO: 41600 events read in total (3237ms).
[16:25:39.853] INFO: Test took 4434ms.
[16:25:39.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:39.962] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 633669
[16:25:39.962] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[16:25:39.962] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:40.012] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:40.677] INFO: Expecting 41600 events.
[16:25:42.224] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[16:25:42.224] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[16:25:42.224] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:25:42.224] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a064 80b1 4388 58 268f 4389 58 266f 4388 58 2689 4389 58 2681 4388 58 26ad 861 2860 4388 58 268f 29d 262e 4388 58 26a2 2cd 2868 4389 58 2682 e002 c000
[16:25:42.224] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05e 8000 4188 58 26ae 9d 2862 4da 286a 4188 58 266e 5a 2245 4189 58 268b 4189 58 2683 640 2665 74a 2845 750 2a68 4188 58 268f 699 226a 418a 4a 224c 58 268b 4189 58 268f 4188 58 2669 24d 286f 60d 224a e002 c000
[16:25:42.224] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a05f 8040 418a 58 26a7 41b 246e 604 226f 4189 58 266c 6dc 246f 418b 58 266b 493 204f 561 206b 550 2248 4189 58 268a 4188 58 268f 4188 58 268e 413 2449 418a 58 268f 4188 58 266f e002 c000
[16:25:42.225] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a060 80b1 4188 54 224f 58 26a7 4188 58 266f 4188 58 2685 4188 58 266e 681 224f 4188 58 2688 61a 284d 4189 58 268f 4188 58 268a 601 224e 4189 58 2680 40b 266f 6c8 2029 6c0 2a4a 6c1 222f e002 c000
[16:25:42.225] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a061 90c0 4388 51 e001 c061
[16:25:42.225] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a062 8000 4388 58 26a2 49d 2862 75b 246f 4388 58 266f 15b 284f 4389 58 268e 648 2668 4388 58 2689 4388 58 2683 4389 58 268f 4389 58 26a1 4388 58 2685 e002 c000
[16:25:42.225] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a063 8040 4388 58 268f 69a 266a 4388 58 2682 419 246d 4388 58 266e 4388 58 2683 4389 58 26a2 511 284a 4388 58 268a 21d 244b 48b 202e 4388 58 26a6 4389 58 266f 103 2462 103 204a 503 282e e002 c000
[16:25:42.225] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:25:42.225] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a164 80b1 4388 58 266f 4388 1d 2048 58 268f 2cc 2246 849 2268 4388 58 268f 4388 58 268d 4388 58 2681 4388 58 2683 4388 58 2688 4388 58 2682 414 2a2e e002 c000
[16:25:42.225] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15e 8000 4188 58 266f 411 284a 4188 58 2681 81b 2466 4188 58 2685 84a 2a4f 4188 58 2680 241 2a2a 4189 58 2664 359 2069 4189 58 2683 441 264f 4188 58 266d 418b 58 266a e002 c000
[16:25:42.225] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a15f 8040 418b 58 2682 418a 58 268f 418b 58 268c 4188 58 2681 418a 58 268b 418a 58 266f 4188 58 266f 4189 58 2667 e0 2626 e002 c000
[16:25:42.225] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a160 80b1 4189 58 2682 50d 282e 552 224f 4189 58 268f 314 2648 4189 58 268a 4188 58 266f 65a 264f 4189 58 266f 51c 2642 542 2a46 543 224a 4189 58 266e 4188 58 2682 4189 58 2665 e002 c000
[16:25:42.225] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a161 90c0 4388 5a 2a8f aa8 2058 682 2689 a4a 4388 58 268f 691 242f 4388 58 268f 45a 2842 4389 58 2663 4389 58 2688 4388 58 2689 4389 58 266f e002 c000
[16:25:42.225] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a162 8000 4388 58 2688 4388 58 268d 44c 2862 651 224a 4389 13 2062 58 2682 4389 58 266f 345 2445 345 204d 518 2646 4388 5d 204d 58 2663 444 2a41 691 2a49 4388 58 2682 4389 58 2684 322 2888 4389 20 262c 58 2682 e002 c000
[16:25:42.225] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a163 8040 4388 58 268a 4389 58 268f 851 2862 4388 58 268f 742 244f 4388 58 2688 91 2262 4388 1a 2040 58 2685 619 2046 619 2242 4389 58 266f 4388 58 2683 4388 58 266f e002 c000
[16:25:44.438] INFO: 41600 events read in total (3234ms).
[16:25:44.443] INFO: Test took 4431ms.
[16:25:44.549] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:44.549] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632649
[16:25:44.549] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[16:25:44.549] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:44.599] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:45.264] INFO: Expecting 41600 events.
[16:25:49.033] INFO: 41600 events read in total (3242ms).
[16:25:49.037] INFO: Test took 4439ms.
[16:25:49.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:49.146] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 634354
[16:25:49.146] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[16:25:49.147] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:49.197] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:49.862] INFO: Expecting 41600 events.
[16:25:53.623] INFO: 41600 events read in total (3234ms).
[16:25:53.628] INFO: Test took 4431ms.
[16:25:53.732] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:53.733] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 633307
[16:25:53.733] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[16:25:53.733] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:53.783] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:54.449] INFO: Expecting 41600 events.
[16:25:58.219] INFO: 41600 events read in total (3244ms).
[16:25:58.223] INFO: Test took 4440ms.
[16:25:58.331] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:58.331] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 634037
[16:25:58.331] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[16:25:58.332] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:25:58.382] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:25:59.048] INFO: Expecting 41600 events.
[16:26:02.773] INFO: 41600 events read in total (3199ms).
[16:26:02.778] INFO: Test took 4396ms.
[16:26:02.882] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:02.882] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632833
[16:26:02.882] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[16:26:02.882] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:02.933] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:03.598] INFO: Expecting 41600 events.
[16:26:07.344] INFO: 41600 events read in total (3219ms).
[16:26:07.348] INFO: Test took 4415ms.
[16:26:07.455] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:07.455] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 632536
[16:26:07.455] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[16:26:07.455] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:07.505] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:08.170] INFO: Expecting 41600 events.
[16:26:11.766] INFO: 41600 events read in total (3069ms).
[16:26:11.770] INFO: Test took 4265ms.
[16:26:11.874] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:11.874] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 629344
[16:26:11.874] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[16:26:11.874] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:12.146] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 170 eff = 0.999038
[16:26:12.146] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 166 eff = 0.999327
[16:26:12.146] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 154 eff = 0.998702
[16:26:12.146] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 160 eff = 0.998438
[16:26:12.147] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 151 eff = 0.959712
[16:26:12.147] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 163 eff = 0.997644
[16:26:12.147] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 154 eff = 0.998245
[16:26:12.147] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 171 eff = 0.998077
[16:26:12.147] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 147 eff = 0.998221
[16:26:12.147] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 161 eff = 0.99774
[16:26:12.147] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 151 eff = 0.997981
[16:26:12.148] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 167 eff = 0.998125
[16:26:12.148] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 131 eff = 0.997837
[16:26:12.148] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 144 eff = 0.99887
[16:26:12.148] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 158 eff = 0.999495
[16:26:12.148] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 142 eff = 0.997933
[16:26:15.979] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): xpixelalive
[16:26:15.979] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[16:26:15.979] INFO: ----------------------------------------------------------------------
[16:26:15.979] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:26:15.979] INFO: ----------------------------------------------------------------------
[16:26:15.980] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[16:26:15.980] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[16:26:15.980] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[16:26:15.980] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[16:26:15.980] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[16:26:15.980] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[16:26:15.980] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[16:26:15.980] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[16:26:15.980] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[16:26:15.980] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[16:26:15.980] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[16:26:15.980] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[16:26:15.998] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[16:26:16.667] INFO: Expecting 208000 events.
[16:26:31.663] INFO: 208000 events read in total (14470ms).
[16:26:31.678] INFO: Test took 15680ms.
[16:26:32.175] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:32.175] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2877857
[16:26:32.175] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[16:26:32.176] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[16:26:32.596] INFO: number of dead pixels (per ROC): 0 0 0 0 160 1 0 0 0 0 0 0 0 0 0 0
[16:26:32.596] INFO: number of red-efficiency pixels: 209 141 305 337 533 436 373 349 377 422 411 393 506 237 95 263
[16:26:32.596] INFO: number of X-ray hits detected: 142237 90080 148840 207932 205487 220595 217754 196361 190724 222673 218623 208063 212981 143883 80033 105192
[16:26:32.596] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:26:32.596] INFO: number of Vcal hits detected: 207779 207841 207675 207647 199599 207479 207590 207626 207614 207536 207553 207580 207442 207736 207904 207726
[16:26:32.596] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 99.9
[16:26:32.596] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 96.0 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.7 99.9 100.0 99.9
[16:26:32.596] INFO: X-ray hit rate [MHz/cm2]: 41.7 26.4 43.6 60.9 60.2 64.7 63.8 57.6 55.9 65.3 64.1 61.0 62.4 42.2 23.5 30.8
[16:26:32.596] INFO: PixTestHighRate::doXPixelAlive() done
[16:26:32.641] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[16:26:32.641] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[16:26:32.641] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[16:26:32.641] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[16:26:32.641] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[16:26:32.641] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[16:26:32.641] INFO: PixTest:: pg_setup set to default.
[16:26:38.051] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:26:38.051] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = highRate_xraymap_C12_V0 -> highRate_xraymap_mod
[16:26:40.315] DEBUG: <PixTab.cc/buttonClicked:L308> xxxPressed(): rundaq
[16:26:40.315] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: rundaq
[16:26:40.316] INFO: ----------------------------------------------------------------------
[16:26:40.316] INFO: PixTestHighRate::runDaq() running for 2 seconds
[16:26:40.316] INFO: ----------------------------------------------------------------------
[16:26:40.522] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: clk: 4
[16:26:40.522] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: ctr: 4
[16:26:40.522] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: sda: 19
[16:26:40.522] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: tin: 9
[16:26:40.522] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: level: 15
[16:26:40.522] DEBUG: <PixTestHighRate.cc/doRunDaq:L1050> old set: triggerdelay: 0
[16:26:40.522] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: clk: 4
[16:26:40.522] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: ctr: 4
[16:26:40.522] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: sda: 19
[16:26:40.522] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: tin: 9
[16:26:40.522] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: level: 15
[16:26:40.522] DEBUG: <PixTestHighRate.cc/doRunDaq:L1066> setting: triggerdelay: 20
[16:26:41.479] INFO: PixTestHighRate::doHitMap start TriggerLoop with trigger frequency 20 kHz, period 2011 and duration 2 seconds
[16:26:43.482] INFO: data taking finished, elapsed time: 2 seconds.
[16:26:45.185] DEBUG: <PixTestHighRate.cc/fillMap:L1169> Processing Data: 39822 events with 535079 pixels
[16:26:45.191] INFO: PixTest:: pg_setup set to default.
[16:26:45.317] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C0_V0
[16:26:45.318] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C1_V0
[16:26:45.318] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C2_V0
[16:26:45.318] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C3_V0
[16:26:45.318] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C4_V0
[16:26:45.318] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C5_V0
[16:26:45.318] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C6_V0
[16:26:45.318] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C7_V0
[16:26:45.318] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C8_V0
[16:26:45.319] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C9_V0
[16:26:45.319] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C10_V0
[16:26:45.319] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C11_V0
[16:26:45.319] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C12_V0
[16:26:45.319] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C13_V0
[16:26:45.319] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C14_V0
[16:26:45.319] DEBUG: <PixTestHighRate.cc/doRunDaq:L1084> analyzing hitMap_daqbbtest_C15_V0
[16:26:45.320] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: clk: 4
[16:26:45.320] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: ctr: 4
[16:26:45.320] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: sda: 19
[16:26:45.320] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: tin: 9
[16:26:45.320] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: level: 15
[16:26:45.320] DEBUG: <PixTestHighRate.cc/doRunDaq:L1096> resetting: triggerdelay: 0
[16:26:45.320] INFO: Pixels without X-ray hits (per ROC): 451 1016 548 139 282 114 144 148 259 117 130 195 122 577 1064 540
[16:26:45.320] INFO: PixTestHighRate::doRunDaq() done
[16:27:19.995] DEBUG: <PixTest.cc/moduleMap:L1029> moduleMap histname: what
[16:27:19.995] DEBUG: <PixTest.cc/moduleMap:L1039> h1->GetName() = hitMap_daqbbtest_C12_V0 -> hitMap_daqbbtest_mod
[16:27:33.171] DEBUG: <PixGui.cc/handleButtons:L396> PixGui::exit called
[16:27:33.172] DEBUG: <PixGui.cc/CloseWindow:L335> Final Analog Current: 383.5mA
[16:27:33.173] DEBUG: <PixGui.cc/CloseWindow:L336> Final Digital Current: 467.9mA
[16:27:33.173] DEBUG: <PixGui.cc/CloseWindow:L337> Final Module Temperature: -0.2 C
[16:27:33.173] DEBUG: <PixTestBBMap.cc/~PixTestBBMap:L97> PixTestBBMap dtor
[16:27:33.173] DEBUG: <PixTestBB2Map.cc/~PixTestBB2Map:L115> PixTestBB2Map dtor
[16:27:33.173] DEBUG: <PixTestBB3Map.cc/~PixTestBB3Map:L99> PixTestBB3Map dtor
[16:27:33.173] DEBUG: <PixTestBB4Map.cc/~PixTestBB4Map:L118> PixTestBB4Map dtor
[16:27:33.173] DEBUG: <PixTestCmd.cc/~PixTestCmd:L78> PixTestCmd dtor
[16:27:33.173] DEBUG: <PixTestDaq.cc/~PixTestDaq:L37> PixTestDaq dtor
[16:27:33.173] DEBUG: <PixTestDacDacScan.cc/~PixTestDacDacScan:L136> PixTestDacDacScan dtor
[16:27:33.173] DEBUG: <PixTestDacScan.cc/~PixTestDacScan:L129> PixTestDacScan dtor
[16:27:33.173] DEBUG: <PixTestGainPedestal.cc/~PixTestGainPedestal:L125> PixTestGainPedestal dtor
[16:27:33.173] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[16:27:33.281] DEBUG: <PixTestIV.cc/~PixTestIV:L96> PixTestIV dtor
[16:27:33.281] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[16:27:33.294] DEBUG: <PixTestPretest.cc/~PixTestPretest:L136> PixTestPretest dtor
[16:27:33.294] DEBUG: <PixTestReadback.cc/~PixTestReadback:L89> PixTestReadback dtor, saving tree ...
[16:27:33.295] DEBUG: <PixTestScurves.cc/~PixTestScurves:L142> PixTestScurves dtor
[16:27:33.295] DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor
[16:27:33.295] DEBUG: <PixTestTrim.cc/~PixTestTrim:L103> PixTestTrim dtor
[16:27:33.295] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[16:27:33.297] QUIET: Connection to board 58 closed.
[16:27:33.377] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries