MORE-Web

Test Date: 2016-08-29 13:22
Analysis date: 2017-03-29 12:18
Logfile
hrData_40.log
[10:28:59.406]     INFO: *** Welcome to pxar ***

[10:28:59.406]     INFO: *** Today: 2017/03/29

[10:28:59.426]     INFO: *** Version: v1.9.0-825-g6bc29

[10:28:59.427]     INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-14_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-14_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//dacParameters35_C15.dat

[10:28:59.428]     INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-14_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-14_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//tbmParameters_C0b.dat

[10:28:59.428]     INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-14_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//defaultMaskFile.dat

[10:28:59.428]     INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-14_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-14_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//trimParameters35_C15.dat

[10:28:59.497]     INFO:         clk: 4

[10:28:59.497]     INFO:         ctr: 4

[10:28:59.497]     INFO:         sda: 19

[10:28:59.497]     INFO:         tin: 9

[10:28:59.497]     INFO:         level: 15

[10:28:59.497]     INFO:         triggerdelay: 0

[10:28:59.497]    QUIET: Instanciating API for pxar v1.9.0+825~g6bc290c

[10:28:59.497]     INFO: Log level: DEBUG

[10:28:59.515]    QUIET: Connection to board DTB_WREKRL opened.

[10:28:59.519]     INFO: DTB startup information

--- DTB info------------------------------------------

Board id:    33

HW version:  DTB1.2

FW version:  4.2

SW version:  4.5

USB id:      DTB_WREKRL

MAC address: 40D855118021

Hostname:    pixelDTB033

Comment:     

------------------------------------------------------

[10:28:59.522]     INFO: RPC call hashes of host and DTB match: 398089610

[10:29:01.068]     INFO: DUT info: 

[10:29:01.068]     INFO: The DUT currently contains the following objects:

[10:29:01.068]     INFO:  2 TBM Cores tbm08c (2 ON)

[10:29:01.077]     INFO: 	TBM Core alpha (0): 7 registers set

[10:29:01.078]     INFO: 	TBM Core beta  (1): 7 registers set

[10:29:01.078]     INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs

[10:29:01.078]     INFO: 	ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.078]     INFO: 	ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpall: checkbox(0)

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpproblematic: checkbox(0)

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 5

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   vcals: 250

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   vcals: 222

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   plwidth: 35

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   pix: 11,20

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   targetia: 24

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpall: checkbox(0)

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpproblematic: checkbox(0)

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 5

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   vcals: 250

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   savecaldelscan: checkbox(0)

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 100

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   cals: 1

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   caldello: 80

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   caldelhi: 200

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   caldelstep: 10

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   vthrcomplo: 70

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   vthrcomphi: 130

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   vthrcompstep: 5

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   noisypixels: 10

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   vcal: 255

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   cut: 0.5

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-

[10:29:01.121]    DEBUG: <PixTestParameters.cc/dump:L107>   phmap: checkbox

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   dac1: caldel

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   dac1lo: 0

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   dac1hi: 255

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   dac2: vthrcomp

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   dac2lo: 0

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   dac2hi: 255

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   pix: 11,20

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   phmap: checkbox(1)

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   allpixels: checkbox(0)

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   unmasked: checkbox(0)

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   dac: vcal

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   daclo: 0

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   dachi: 255

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   pix: 11,20

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   showfits: checkbox(0)

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   extended: checkbox(0)

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   dumphists: checkbox(0)

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   vcalstep: 10

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   measure: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   fit: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   save: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   pix: 11,20

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   trimhotpixels: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   trimhotpixelthr: 200

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   runsecondshotpixels: 10

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   savetrimbits: checkbox(1)

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   maskuntrimmable: checkbox(1)

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   maskhotpixels: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   savemaskfile: checkbox(0)

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   maskfilename: default

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   caldelscan: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   xpixelalive: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 50

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   vcal: 200

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   xnoisemaps: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   daclo: 0

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   dachi: 100

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   dacs/step: 20

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   rundaq: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   trgfrequency(khz): 20

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   runseconds: 2

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   triggerdelay: 20

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   delaytbm: checkbox

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   filltree: checkbox

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   port: /dev/FIXME

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   voltagestart: 0

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   voltagestop: 600

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   voltagestep: 5

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   delay: 1

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   compliance(ua): 100

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   safetymarginlow: 20

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   saturationvcal: 100

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   quantilesaturation: 0.98

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   phmap: checkbox(0)

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   vcal: 200

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   alivetest: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   masktest: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   addressdecodingtest: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   programroc: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   checkidig: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   targetia: 24

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   setvana: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   iterations: 100

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   settimings: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   findtiming: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   findworkingpixel: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   setvthrcompcaldel: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   pix: 11,20

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   vcal: 250

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   deltavthrcomp: 50

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   fraccaldel: 0.5

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 5

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   savedacs: button

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-

[10:29:01.122]    DEBUG: <PixTestParameters.cc/dump:L107>   calibratevd: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   calibrateva: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   calibrateia: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   readbackvbg: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   getcalibratedvbg: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   usecalvd: checkbox(1)

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   usecalva: checkbox(0)

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   setvana: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   adjustvcal: checkbox(0)

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpall: checkbox(0)

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpproblematic: checkbox(0)

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   dumpoutputfile: checkbox(0)

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 50

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   dac: Vcal

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   daclo: 0

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   dachi: 200

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   dacs/step: -1

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig/step: -1

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   scurves: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   targetclk: 4

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 10000

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   clocksdascan: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   notokenpass: checkbox(0)

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   ignorereadback: checkbox(0)

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   phasescan: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   levelscan: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   tbmphasescan: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   rocdelayscan: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   timingtest: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   saveparameters: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   trim: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   ntrig: 8

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   vcal: 35

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   trimbits: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   maskhotpixels: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   savemaskfile: checkbox(0)

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   maskfilename: default

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   source: Ag

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   phrun: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   runseconds: 100

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   trgfrequency(khz): 100

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   ratescan: button

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   vthrcompmin: 10

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   vthrcompmax: 80

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   stepseconds: 5

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   delaytbm: checkbox

[10:29:01.123]    DEBUG: <PixTestParameters.cc/dump:L107>   filltree: checkbox

[10:29:01.125]    DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 29052928

[10:29:01.125]    DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0x11e0220

[10:29:01.126]    DEBUG: <PixSetup.cc/init:L88>  fConfigParameters = 0x1106310

[10:29:01.126]    DEBUG: <PixSetup.cc/init:L89>        fPxarMemory = 0x7f58b9d94010

[10:29:01.126]    DEBUG: <PixSetup.cc/init:L90>         fPxarMemHi = 0x7f58bffff510

[10:29:01.126]    DEBUG: <PixSetup.cc/init:L106> PixSetup init done;  getCurrentRSS() = 29118464 fPxarMemory = 0x7f58b9d94010

[10:29:01.127]    DEBUG: <pXar.cc/main:L223> Initial Analog Current: 410.8mA

[10:29:01.128]    DEBUG: <pXar.cc/main:L224> Initial Digital Current: 475.1mA

[10:29:01.128]    DEBUG: <pXar.cc/main:L225> Initial Module Temperature: 2.1 C

[10:29:01.129]    DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()

[10:29:01.540]     INFO: enter 'restricted' command line mode

[10:29:01.540]     INFO: enter test to run

[10:29:33.339]     INFO:   test: PixelAlive no parameter change

[10:29:33.339]     INFO:   running: pixelalive

[10:29:33.339]    DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()

[10:29:33.357]    DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)

[10:29:33.357]    DEBUG: <PixTestAlive.cc/runCommand:L62> running command: alivetest

[10:29:33.377]     INFO:    ----------------------------------------------------------------------

[10:29:33.377]     INFO:    PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)

[10:29:33.377]     INFO:    ----------------------------------------------------------------------

[10:29:33.380]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[10:29:33.697]     INFO: Expecting 41600 events.

[10:29:37.000]     INFO: 41600 events read in total (3584ms).

[10:29:38.164]     INFO: Test took 4784ms.

[10:29:38.178]     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:29:38.178]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 66485

[10:29:38.178]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15

[10:29:38.704]     INFO: PixTestAlive::aliveTest() done

[10:29:38.704]     INFO: number of dead pixels (per ROC):     1    1    0    0    0    0    0   70    0    0    0    1    2    0    0    0

[10:29:38.705]    DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels:     1    1    0    0    0    0    0   73    0    0    0    1    2    0    0    0

[10:29:38.705]    DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor

[10:29:38.847]     INFO: enter test to run

[10:29:45.827]     INFO:   test: timing no parameter change

[10:29:45.827]     INFO:   running: timing

[10:29:45.827]    DEBUG: <PixTestTiming.cc/setParameter:L59> PixTestTiming::PixTest() targetclk = 4

[10:29:45.827]    DEBUG: <PixTestTiming.cc/setParameter:L63> PixTestTiming::PixTest() ntrig = 10000

[10:29:45.827]    DEBUG: <PixTestTiming.cc/setParameter:L47> fNoTokenPass: 0

[10:29:45.827]    DEBUG: <PixTestTiming.cc/setParameter:L54> fIgnoreReadBack: 0

[10:29:45.827]    DEBUG: <PixTestTiming.cc/init:L73> PixTestTiming::init()

[10:29:45.830]     INFO: ######################################################################

[10:29:45.830]     INFO: PixTestTiming::doTest()

[10:29:45.830]     INFO: ######################################################################

[10:29:45.830]     INFO:    ----------------------------------------------------------------------

[10:29:45.830]     INFO:    PixTestTiming::TBMPhaseScan()

[10:29:45.831]     INFO:    ----------------------------------------------------------------------

[10:29:45.831]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 0 400MHz Phase: 0 TBM Phase: 00000000

[10:29:47.847]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 0 400MHz Phase: 1 TBM Phase: 00000100

[10:29:50.240]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 0 400MHz Phase: 2 TBM Phase: 00001000

[10:29:52.632]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 0 400MHz Phase: 3 TBM Phase: 00001100

[10:29:55.024]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 0 400MHz Phase: 4 TBM Phase: 00010000

[10:29:57.416]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 0 400MHz Phase: 5 TBM Phase: 00010100

[10:29:59.808]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 0 400MHz Phase: 6 TBM Phase: 00011000

[10:30:02.199]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 0 400MHz Phase: 7 TBM Phase: 00011100

[10:30:04.590]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 1 400MHz Phase: 0 TBM Phase: 00100000

[10:30:06.984]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 1 400MHz Phase: 1 TBM Phase: 00100100

[10:30:09.375]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 1 400MHz Phase: 2 TBM Phase: 00101000

[10:30:11.764]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 1 400MHz Phase: 3 TBM Phase: 00101100

[10:30:14.158]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 1 400MHz Phase: 4 TBM Phase: 00110000

[10:30:16.549]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 1 400MHz Phase: 5 TBM Phase: 00110100

[10:30:18.940]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 1 400MHz Phase: 6 TBM Phase: 00111000

[10:30:21.334]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 1 400MHz Phase: 7 TBM Phase: 00111100

[10:30:23.724]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 2 400MHz Phase: 0 TBM Phase: 01000000

[10:30:25.250]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 2 400MHz Phase: 1 TBM Phase: 01000100

[10:30:26.772]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 2 400MHz Phase: 2 TBM Phase: 01001000

[10:30:28.297]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 2 400MHz Phase: 3 TBM Phase: 01001100

[10:30:29.821]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 2 400MHz Phase: 4 TBM Phase: 01010000

[10:30:31.343]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 2 400MHz Phase: 5 TBM Phase: 01010100

[10:30:32.866]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 2 400MHz Phase: 6 TBM Phase: 01011000

[10:30:34.390]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 2 400MHz Phase: 7 TBM Phase: 01011100

[10:30:35.912]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 3 400MHz Phase: 0 TBM Phase: 01100000

[10:30:38.188]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 3 400MHz Phase: 1 TBM Phase: 01100100

[10:30:40.464]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 3 400MHz Phase: 2 TBM Phase: 01101000

[10:30:42.740]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 3 400MHz Phase: 3 TBM Phase: 01101100

[10:30:45.018]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 3 400MHz Phase: 4 TBM Phase: 01110000

[10:30:47.484]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 3 400MHz Phase: 5 TBM Phase: 01110100

[10:30:49.760]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 3 400MHz Phase: 6 TBM Phase: 01111000

[10:30:52.037]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 3 400MHz Phase: 7 TBM Phase: 01111100

[10:30:54.314]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 4 400MHz Phase: 0 TBM Phase: 10000000

[10:30:55.836]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 4 400MHz Phase: 1 TBM Phase: 10000100

[10:30:57.361]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 4 400MHz Phase: 2 TBM Phase: 10001000

[10:30:58.883]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 4 400MHz Phase: 3 TBM Phase: 10001100

[10:31:00.407]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 4 400MHz Phase: 4 TBM Phase: 10010000

[10:31:02.494]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 4 400MHz Phase: 5 TBM Phase: 10010100

[10:31:04.019]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 4 400MHz Phase: 6 TBM Phase: 10011000

[10:31:05.543]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 4 400MHz Phase: 7 TBM Phase: 10011100

[10:31:07.066]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 5 400MHz Phase: 0 TBM Phase: 10100000

[10:31:09.457]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 5 400MHz Phase: 1 TBM Phase: 10100100

[10:31:10.979]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 5 400MHz Phase: 2 TBM Phase: 10101000

[10:31:12.502]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 5 400MHz Phase: 3 TBM Phase: 10101100

[10:31:14.026]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 5 400MHz Phase: 4 TBM Phase: 10110000

[10:31:19.824]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 5 400MHz Phase: 5 TBM Phase: 10110100

[10:31:32.663]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 5 400MHz Phase: 6 TBM Phase: 10111000

[10:31:34.186]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 5 400MHz Phase: 7 TBM Phase: 10111100

[10:31:47.031]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 6 400MHz Phase: 0 TBM Phase: 11000000

[10:31:49.422]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 6 400MHz Phase: 1 TBM Phase: 11000100

[10:31:51.815]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 6 400MHz Phase: 2 TBM Phase: 11001000

[10:31:54.206]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 6 400MHz Phase: 3 TBM Phase: 11001100

[10:31:56.597]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 6 400MHz Phase: 4 TBM Phase: 11010000

[10:31:59.063]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 6 400MHz Phase: 5 TBM Phase: 11010100

[10:32:01.455]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 6 400MHz Phase: 6 TBM Phase: 11011000

[10:32:03.847]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 6 400MHz Phase: 7 TBM Phase: 11011100

[10:32:06.238]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 7 400MHz Phase: 0 TBM Phase: 11100000

[10:32:08.631]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 7 400MHz Phase: 1 TBM Phase: 11100100

[10:32:11.025]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 7 400MHz Phase: 2 TBM Phase: 11101000

[10:32:13.417]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 7 400MHz Phase: 3 TBM Phase: 11101100

[10:32:15.806]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 7 400MHz Phase: 4 TBM Phase: 11110000

[10:32:17.518]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 7 400MHz Phase: 5 TBM Phase: 11110100

[10:32:19.912]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 7 400MHz Phase: 6 TBM Phase: 11111000

[10:32:22.303]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 0 160MHz Phase: 7 400MHz Phase: 7 TBM Phase: 11111100

[10:32:24.695]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 0 400MHz Phase: 0 TBM Phase: 00000000

[10:32:27.092]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 0 400MHz Phase: 1 TBM Phase: 00000100

[10:32:29.487]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 0 400MHz Phase: 2 TBM Phase: 00001000

[10:32:31.877]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 0 400MHz Phase: 3 TBM Phase: 00001100

[10:32:34.271]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 0 400MHz Phase: 4 TBM Phase: 00010000

[10:32:36.667]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 0 400MHz Phase: 5 TBM Phase: 00010100

[10:32:39.062]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 0 400MHz Phase: 6 TBM Phase: 00011000

[10:32:41.458]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 0 400MHz Phase: 7 TBM Phase: 00011100

[10:32:43.852]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 1 400MHz Phase: 0 TBM Phase: 00100000

[10:32:46.248]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 1 400MHz Phase: 1 TBM Phase: 00100100

[10:32:48.643]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 1 400MHz Phase: 2 TBM Phase: 00101000

[10:32:51.039]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 1 400MHz Phase: 3 TBM Phase: 00101100

[10:32:53.435]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 1 400MHz Phase: 4 TBM Phase: 00110000

[10:32:55.830]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 1 400MHz Phase: 5 TBM Phase: 00110100

[10:32:58.227]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 1 400MHz Phase: 6 TBM Phase: 00111000

[10:33:00.622]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 1 400MHz Phase: 7 TBM Phase: 00111100

[10:33:03.017]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 2 400MHz Phase: 0 TBM Phase: 01000000

[10:33:04.540]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 2 400MHz Phase: 1 TBM Phase: 01000100

[10:33:06.062]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 2 400MHz Phase: 2 TBM Phase: 01001000

[10:33:07.585]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 2 400MHz Phase: 3 TBM Phase: 01001100

[10:33:09.108]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 2 400MHz Phase: 4 TBM Phase: 01010000

[10:33:10.631]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 2 400MHz Phase: 5 TBM Phase: 01010100

[10:33:12.155]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 2 400MHz Phase: 6 TBM Phase: 01011000

[10:33:13.678]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 2 400MHz Phase: 7 TBM Phase: 01011100

[10:33:15.200]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 3 400MHz Phase: 0 TBM Phase: 01100000

[10:33:16.723]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 3 400MHz Phase: 1 TBM Phase: 01100100

[10:33:19.902]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 3 400MHz Phase: 2 TBM Phase: 01101000

[10:33:23.261]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 3 400MHz Phase: 3 TBM Phase: 01101100

[10:33:26.434]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 3 400MHz Phase: 4 TBM Phase: 01110000

[10:33:29.792]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 3 400MHz Phase: 5 TBM Phase: 01110100

[10:33:32.679]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 3 400MHz Phase: 6 TBM Phase: 01111000

[10:33:36.043]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 3 400MHz Phase: 7 TBM Phase: 01111100

[10:33:39.410]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 4 400MHz Phase: 0 TBM Phase: 10000000

[10:33:40.935]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 4 400MHz Phase: 1 TBM Phase: 10000100

[10:33:42.458]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 4 400MHz Phase: 2 TBM Phase: 10001000

[10:33:43.982]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 4 400MHz Phase: 3 TBM Phase: 10001100

[10:33:45.505]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 4 400MHz Phase: 4 TBM Phase: 10010000

[10:33:47.028]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 4 400MHz Phase: 5 TBM Phase: 10010100

[10:33:48.552]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 4 400MHz Phase: 6 TBM Phase: 10011000

[10:33:50.074]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 4 400MHz Phase: 7 TBM Phase: 10011100

[10:33:51.598]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 5 400MHz Phase: 0 TBM Phase: 10100000

[10:33:53.992]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 5 400MHz Phase: 1 TBM Phase: 10100100

[10:33:55.515]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 5 400MHz Phase: 2 TBM Phase: 10101000

[10:34:08.342]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 5 400MHz Phase: 3 TBM Phase: 10101100

[10:34:21.180]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 5 400MHz Phase: 4 TBM Phase: 10110000

[10:34:23.080]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 5 400MHz Phase: 5 TBM Phase: 10110100

[10:34:24.601]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 5 400MHz Phase: 6 TBM Phase: 10111000

[10:34:37.425]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 5 400MHz Phase: 7 TBM Phase: 10111100

[10:34:38.945]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 6 400MHz Phase: 0 TBM Phase: 11000000

[10:34:41.340]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 6 400MHz Phase: 1 TBM Phase: 11000100

[10:34:43.735]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 6 400MHz Phase: 2 TBM Phase: 11001000

[10:34:46.131]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 6 400MHz Phase: 3 TBM Phase: 11001100

[10:34:48.527]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 6 400MHz Phase: 4 TBM Phase: 11010000

[10:34:50.049]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 6 400MHz Phase: 5 TBM Phase: 11010100

[10:34:52.447]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 6 400MHz Phase: 6 TBM Phase: 11011000

[10:34:54.842]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 6 400MHz Phase: 7 TBM Phase: 11011100

[10:34:57.236]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 7 400MHz Phase: 0 TBM Phase: 11100000

[10:34:59.631]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 7 400MHz Phase: 1 TBM Phase: 11100100

[10:35:02.025]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 7 400MHz Phase: 2 TBM Phase: 11101000

[10:35:04.422]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 7 400MHz Phase: 3 TBM Phase: 11101100

[10:35:06.816]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 7 400MHz Phase: 4 TBM Phase: 11110000

[10:35:08.340]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 7 400MHz Phase: 5 TBM Phase: 11110100

[10:35:10.735]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 7 400MHz Phase: 6 TBM Phase: 11111000

[10:35:13.131]    DEBUG: <PixTestTiming.cc/TBMPhaseScan:L435> TBM Core: 1 160MHz Phase: 7 400MHz Phase: 7 TBM Phase: 11111100

[10:35:15.909]     INFO: TBM Phase Settings: 248

[10:35:15.909]     INFO: 400MHz Phase: 6

[10:35:15.909]     INFO: 160MHz Phase: 7

[10:35:15.909]     INFO: Functional Phase Area: 4

[10:35:15.929]     INFO: Test took 330099 ms.

[10:35:15.929]     INFO: PixTestTiming::TBMPhaseScan() done.

[10:35:15.930]     INFO:    ----------------------------------------------------------------------

[10:35:15.930]     INFO:    PixTestTiming::ROCDelayScan()

[10:35:15.930]     INFO:    ----------------------------------------------------------------------

[10:35:15.930]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 0 ROC Port0: 0 ROCDelay Setting: 11000000

[10:35:17.454]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 0 ROC Port0: 1 ROCDelay Setting: 11000001

[10:35:19.352]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 0 ROC Port0: 2 ROCDelay Setting: 11000010

[10:35:21.253]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 0 ROC Port0: 3 ROCDelay Setting: 11000011

[10:35:23.152]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 0 ROC Port0: 4 ROCDelay Setting: 11000100

[10:35:25.051]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 0 ROC Port0: 5 ROCDelay Setting: 11000101

[10:35:26.949]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 0 ROC Port0: 6 ROCDelay Setting: 11000110

[10:35:28.662]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 0 ROC Port0: 7 ROCDelay Setting: 11000111

[10:35:30.185]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 1 ROC Port0: 0 ROCDelay Setting: 11001000

[10:35:31.709]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 1 ROC Port0: 1 ROCDelay Setting: 11001001

[10:35:33.234]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 1 ROC Port0: 2 ROCDelay Setting: 11001010

[10:35:34.757]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 1 ROC Port0: 3 ROCDelay Setting: 11001011

[10:35:36.280]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 1 ROC Port0: 4 ROCDelay Setting: 11001100

[10:35:37.804]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 1 ROC Port0: 5 ROCDelay Setting: 11001101

[10:35:39.328]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 1 ROC Port0: 6 ROCDelay Setting: 11001110

[10:35:40.850]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 1 ROC Port0: 7 ROCDelay Setting: 11001111

[10:35:42.376]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 2 ROC Port0: 0 ROCDelay Setting: 11010000

[10:35:43.899]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 2 ROC Port0: 1 ROCDelay Setting: 11010001

[10:35:45.422]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 2 ROC Port0: 2 ROCDelay Setting: 11010010

[10:35:47.845]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 2 ROC Port0: 3 ROCDelay Setting: 11010011

[10:35:50.268]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 2 ROC Port0: 4 ROCDelay Setting: 11010100

[10:35:52.693]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 2 ROC Port0: 5 ROCDelay Setting: 11010101

[10:35:55.118]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 2 ROC Port0: 6 ROCDelay Setting: 11010110

[10:35:57.543]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 2 ROC Port0: 7 ROCDelay Setting: 11010111

[10:35:59.066]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 3 ROC Port0: 0 ROCDelay Setting: 11011000

[10:36:00.589]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 3 ROC Port0: 1 ROCDelay Setting: 11011001

[10:36:02.113]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 3 ROC Port0: 2 ROCDelay Setting: 11011010

[10:36:04.535]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 3 ROC Port0: 3 ROCDelay Setting: 11011011

[10:36:06.960]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 3 ROC Port0: 4 ROCDelay Setting: 11011100

[10:36:09.385]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 3 ROC Port0: 5 ROCDelay Setting: 11011101

[10:36:11.808]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 3 ROC Port0: 6 ROCDelay Setting: 11011110

[10:36:14.232]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 3 ROC Port0: 7 ROCDelay Setting: 11011111

[10:36:15.754]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 4 ROC Port0: 0 ROCDelay Setting: 11100000

[10:36:17.279]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 4 ROC Port0: 1 ROCDelay Setting: 11100001

[10:36:18.802]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 4 ROC Port0: 2 ROCDelay Setting: 11100010

[10:36:21.226]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 4 ROC Port0: 3 ROCDelay Setting: 11100011

[10:36:23.650]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 4 ROC Port0: 4 ROCDelay Setting: 11100100

[10:36:26.075]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 4 ROC Port0: 5 ROCDelay Setting: 11100101

[10:36:28.499]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 4 ROC Port0: 6 ROCDelay Setting: 11100110

[10:36:30.022]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 4 ROC Port0: 7 ROCDelay Setting: 11100111

[10:36:31.544]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 5 ROC Port0: 0 ROCDelay Setting: 11101000

[10:36:33.069]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 5 ROC Port0: 1 ROCDelay Setting: 11101001

[10:36:34.592]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 5 ROC Port0: 2 ROCDelay Setting: 11101010

[10:36:37.017]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 5 ROC Port0: 3 ROCDelay Setting: 11101011

[10:36:39.439]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 5 ROC Port0: 4 ROCDelay Setting: 11101100

[10:36:41.862]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 5 ROC Port0: 5 ROCDelay Setting: 11101101

[10:36:44.286]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 5 ROC Port0: 6 ROCDelay Setting: 11101110

[10:36:46.712]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 5 ROC Port0: 7 ROCDelay Setting: 11101111

[10:36:48.236]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 6 ROC Port0: 0 ROCDelay Setting: 11110000

[10:36:49.758]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 6 ROC Port0: 1 ROCDelay Setting: 11110001

[10:36:51.280]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 6 ROC Port0: 2 ROCDelay Setting: 11110010

[10:36:53.698]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 6 ROC Port0: 3 ROCDelay Setting: 11110011

[10:36:56.121]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 6 ROC Port0: 4 ROCDelay Setting: 11110100

[10:36:58.555]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 6 ROC Port0: 5 ROCDelay Setting: 11110101

[10:37:00.976]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 6 ROC Port0: 6 ROCDelay Setting: 11110110

[10:37:03.395]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 6 ROC Port0: 7 ROCDelay Setting: 11110111

[10:37:04.918]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 7 ROC Port0: 0 ROCDelay Setting: 11111000

[10:37:06.442]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 7 ROC Port0: 1 ROCDelay Setting: 11111001

[10:37:07.964]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 7 ROC Port0: 2 ROCDelay Setting: 11111010

[10:37:09.487]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 7 ROC Port0: 3 ROCDelay Setting: 11111011

[10:37:11.009]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 7 ROC Port0: 4 ROCDelay Setting: 11111100

[10:37:12.531]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 7 ROC Port0: 5 ROCDelay Setting: 11111101

[10:37:14.054]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 7 ROC Port0: 6 ROCDelay Setting: 11111110

[10:37:15.577]    DEBUG: <PixTestTiming.cc/ROCDelayScan:L546> Token Header/Trailer Delay: 11 ROC Port1: 7 ROC Port0: 7 ROCDelay Setting: 11111111

[10:37:17.483]     INFO: ROC Delay Settings: 227

[10:37:17.483]     INFO: ROC Header-Trailer/Token Delay: 11

[10:37:17.483]     INFO: ROC Port 0 Delay: 3

[10:37:17.483]     INFO: ROC Port 1 Delay: 4

[10:37:17.483]     INFO: Functional ROC Area: 4

[10:37:17.487]     INFO: Test took 121557 ms.

[10:37:17.487]     INFO: PixTestTiming::ROCDelayScan() done.

[10:37:17.487]     INFO:    ----------------------------------------------------------------------

[10:37:17.487]     INFO:    PixTestTiming::TimingTest()

[10:37:17.487]     INFO:    ----------------------------------------------------------------------

[10:37:18.629]    DEBUG: <PixTest.cc/getEvents:L2459> Event: ====== 0 ====== a001 8000 4608 4608 4608 4608 4608 4608 4608 4608 e062 c000 a101 8000 4608 4608 4609 4608 4608 4608 4608 4608 e062 c000 

[10:37:18.629]    DEBUG: <PixTest.cc/getEvents:L2459> Event: ====== 0 ====== a002 8040 4608 4608 4608 4608 4608 4608 4608 4608 e022 c000 a102 8040 4608 4608 4608 4608 4608 4608 4608 4608 e022 c000 

[10:37:18.629]    DEBUG: <PixTest.cc/getEvents:L2459> Event: ====== 0 ====== a003 80b1 4608 4608 4608 4608 4608 4608 4608 4608 e022 c000 a103 80b1 4608 4608 4608 4608 460a 4608 4608 4608 e022 c000 

[10:37:18.629]    DEBUG: <PixTest.cc/getEvents:L2463> Collecting 1000000/10000000 Triggers

[10:37:33.800]     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:37:33.800]    DEBUG: <PixTest.cc/getEvents:L2463> Collecting 2000000/10000000 Triggers

[10:37:48.936]     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:37:48.936]    DEBUG: <PixTest.cc/getEvents:L2463> Collecting 3000000/10000000 Triggers

[10:38:04.088]     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:38:04.088]    DEBUG: <PixTest.cc/getEvents:L2463> Collecting 4000000/10000000 Triggers

[10:38:19.107]     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:38:19.107]    DEBUG: <PixTest.cc/getEvents:L2463> Collecting 5000000/10000000 Triggers

[10:38:34.219]     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:38:34.219]    DEBUG: <PixTest.cc/getEvents:L2463> Collecting 6000000/10000000 Triggers

[10:38:49.371]     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:38:49.371]    DEBUG: <PixTest.cc/getEvents:L2463> Collecting 7000000/10000000 Triggers

[10:39:04.456]     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:39:04.456]    DEBUG: <PixTest.cc/getEvents:L2463> Collecting 8000000/10000000 Triggers

[10:39:19.648]     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:39:19.648]    DEBUG: <PixTest.cc/getEvents:L2463> Collecting 9000000/10000000 Triggers

[10:39:34.748]     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:39:34.748]    DEBUG: <PixTest.cc/getEvents:L2470> Collecting 10000000/10000000 Triggers

[10:39:50.043]     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:39:50.420]     INFO: Fetched DAQ statistics. Counters are being reset now.

[10:39:50.437]     INFO: Decoding statistics:

[10:39:50.437]     INFO:   General information:

[10:39:50.437]     INFO: 	 16bit words read:         240000000

[10:39:50.437]     INFO: 	 valid events total:       20000000

[10:39:50.437]     INFO: 	 empty events:             20000000

[10:39:50.437]     INFO: 	 valid events with pixels: 0

[10:39:50.437]     INFO: 	 valid pixel hits:         0

[10:39:50.437]     INFO:   Event errors: 	           0

[10:39:50.437]     INFO: 	 start marker:             0

[10:39:50.437]     INFO: 	 stop marker:              0

[10:39:50.437]     INFO: 	 overflow:                 0

[10:39:50.437]     INFO: 	 invalid 5bit words:       0

[10:39:50.437]     INFO: 	 invalid XOR eye diagram:  0

[10:39:50.437]     INFO:   TBM errors: 		           0

[10:39:50.437]     INFO: 	 flawed TBM headers:       0

[10:39:50.437]     INFO: 	 flawed TBM trailers:      0

[10:39:50.437]     INFO: 	 event ID mismatches:      0

[10:39:50.437]     INFO:   ROC errors: 		           0

[10:39:50.437]     INFO: 	 missing ROC header(s):    0

[10:39:50.437]     INFO: 	 misplaced readback start: 0

[10:39:50.438]     INFO:   Pixel decoding errors:	   0

[10:39:50.438]     INFO: 	 pixel data incomplete:    0

[10:39:50.438]     INFO: 	 pixel address:            0

[10:39:50.438]     INFO: 	 pulse height fill bit:    0

[10:39:50.438]     INFO: 	 buffer corruption:        0

[10:39:50.438]     INFO:    ----------------------------------------------------------------------

[10:39:50.438]     INFO:    The fraction of properly decoded events is 100.00%: 10000000/10000000

[10:39:50.438]     INFO:    ----------------------------------------------------------------------

[10:39:50.438]     INFO:    ----------------------------------------------------------------------

[10:39:50.438]     INFO:    Read back bit status: 1

[10:39:50.438]     INFO:    ----------------------------------------------------------------------

[10:39:50.438]     INFO:    ----------------------------------------------------------------------

[10:39:50.438]     INFO:    Timings are good!

[10:39:50.438]     INFO:    ----------------------------------------------------------------------

[10:39:50.438]     INFO: Test took 152951 ms.

[10:39:50.438]     INFO: PixTestTiming::TimingTest() done.

[10:39:50.458]     INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-14_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//tbmParameters_C0a.dat

[10:39:50.466]     INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-14_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//tbmParameters_C0b.dat

[10:39:50.467]     INFO: PixTestTiming::doTest took 604640 ms.

[10:39:50.467]     INFO: PixTestTiming::doTest() done

[10:39:50.467]    DEBUG: <PixTestTiming.cc/~PixTestTiming:L96> PixTestTiming dtor

[10:39:50.467]     INFO: Write out TBMPhaseScan_0_V0

[10:39:50.467]     INFO: Write out TBMPhaseScan_1_V0

[10:39:50.467]     INFO: Write out CombinedTBMPhaseScan_V0

[10:39:50.468]     INFO: Write out ROCDelayScan3_V0

[10:39:50.469]     INFO: enter test to run

[10:44:16.556]     INFO:   test: Xray setting parameters: ->source=DCLowRate<-

[10:44:16.556]    DEBUG: <PixTestParameters.cc/setTestParameter:L119> PixTestParameters: ->Xray<-

[10:44:16.556]    DEBUG: <PixTestParameters.cc/setTestParameter:L124>  setting  source to new value DCLowRate

[10:44:16.556]     INFO:   running: xray

[10:44:16.557]    DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()

[10:44:16.557]    DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)

[10:44:16.557]     INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-4-14_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-14_FPIXTest-17C-FNAL-160829-1221-300V_2016-08-29_12h21m_1472491319/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat

[10:44:16.855]    DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun

[10:44:16.855]     INFO:    ----------------------------------------------------------------------

[10:44:16.855]     INFO:    PixTestXray::doPhRun() fParRunSeconds = 100

[10:44:16.855]     INFO:    ----------------------------------------------------------------------

[10:44:17.827]     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds,  fEventsMax = 10000000

[10:44:30.278]     INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.

[10:44:30.282]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:44:56.370]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1226791 events.

[10:44:59.310]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 1226791, pixels seen in all events: 5529314

[10:44:59.341]     INFO: Resuming triggers.

[10:45:11.789]     INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.

[10:45:11.793]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:45:37.971]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1226311 events.

[10:45:40.931]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 1226311, pixels seen in all events: 5535160

[10:45:40.996]     INFO: Resuming triggers.

[10:45:53.446]     INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.

[10:45:53.449]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:46:19.494]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1226493 events.

[10:46:22.522]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 1226493, pixels seen in all events: 5533283

[10:46:22.587]     INFO: Resuming triggers.

[10:46:35.036]     INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.

[10:46:35.040]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:47:01.088]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1226490 events.

[10:47:04.043]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 1226490, pixels seen in all events: 5533072

[10:47:04.108]     INFO: Resuming triggers.

[10:47:16.554]     INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.

[10:47:16.572]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:47:42.674]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1226210 events.

[10:47:45.656]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 1226210, pixels seen in all events: 5536332

[10:47:45.688]     INFO: Resuming triggers.

[10:47:58.132]     INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.

[10:47:58.176]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:48:24.255]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1225975 events.

[10:48:27.229]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 1225975, pixels seen in all events: 5539751

[10:48:27.331]     INFO: Resuming triggers.

[10:48:39.775]     INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.

[10:48:39.779]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:49:05.783]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1225981 events.

[10:49:08.782]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 1225981, pixels seen in all events: 5539289

[10:49:08.847]     INFO: Resuming triggers.

[10:49:21.289]     INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.

[10:49:21.293]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:49:47.377]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1225778 events.

[10:49:50.307]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 1225778, pixels seen in all events: 5542251

[10:49:50.379]     INFO: Resuming triggers.

[10:49:51.117]     INFO: data taking finished, elapsed time: 100 seconds.

[10:49:51.313]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:49:53.088]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 72645 events.

[10:49:53.264]    DEBUG: <PixTestXray.cc/processData:L823>  # events read:  72645, pixels seen in all events: 328333

[10:49:53.273]     INFO: PixTest::       pg_setup set to default.

[10:49:53.276]     INFO: PixTestXray::doPhRun() done

[10:49:53.276]    DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor

[10:49:53.410]     INFO: enter test to run

[10:51:42.209]     INFO:   test: Xray setting parameters: ->source=DCHighRate<-

[10:51:42.209]    DEBUG: <PixTestParameters.cc/setTestParameter:L119> PixTestParameters: ->Xray<-

[10:51:42.209]    DEBUG: <PixTestParameters.cc/setTestParameter:L124>  setting  source to new value DCHighRate

[10:51:42.209]     INFO:   running: xray

[10:51:42.209]    DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()

[10:51:42.209]    DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)

[10:51:42.210]    DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun

[10:51:42.210]     INFO:    ----------------------------------------------------------------------

[10:51:42.210]     INFO:    PixTestXray::doPhRun() fParRunSeconds = 100

[10:51:42.210]     INFO:    ----------------------------------------------------------------------

[10:51:43.186]     INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds,  fEventsMax = 10000000

[10:51:50.681]     INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.

[10:51:50.685]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:52:15.002]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738469 events.

[10:52:21.401]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738469, pixels seen in all events: 11389740

[10:52:21.502]     INFO: Resuming triggers.

[10:52:28.000]     INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.

[10:52:29.003]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:52:53.350]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738690 events.

[10:52:59.754]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738690, pixels seen in all events: 11387649

[10:52:59.857]     INFO: Resuming triggers.

[10:53:07.350]     INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.

[10:53:07.353]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:53:32.311]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738140 events.

[10:53:38.740]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738140, pixels seen in all events: 11394793

[10:53:38.841]     INFO: Resuming triggers.

[10:53:46.333]     INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.

[10:53:46.336]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:54:10.848]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738084 events.

[10:54:17.221]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738084, pixels seen in all events: 11394664

[10:54:17.322]     INFO: Resuming triggers.

[10:54:24.815]     INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.

[10:54:24.819]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:54:49.536]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738170 events.

[10:54:55.682]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738170, pixels seen in all events: 11394383

[10:54:55.781]     INFO: Resuming triggers.

[10:55:03.277]     INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.

[10:55:03.280]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:55:27.468]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738406 events.

[10:55:33.442]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738406, pixels seen in all events: 11391618

[10:55:33.541]     INFO: Resuming triggers.

[10:55:41.038]     INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.

[10:55:41.041]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:56:05.141]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738502 events.

[10:56:11.154]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738502, pixels seen in all events: 11390119

[10:56:11.251]     INFO: Resuming triggers.

[10:56:18.745]     INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.

[10:56:18.748]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:56:42.869]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738256 events.

[10:56:48.778]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738256, pixels seen in all events: 11392824

[10:56:48.875]     INFO: Resuming triggers.

[10:56:56.375]     INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.

[10:56:56.378]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:57:20.456]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738883 events.

[10:57:26.438]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738883, pixels seen in all events: 11385584

[10:57:26.534]     INFO: Resuming triggers.

[10:57:34.033]     INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.

[10:57:34.036]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:57:58.206]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738707 events.

[10:58:04.125]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738707, pixels seen in all events: 11387428

[10:58:04.221]     INFO: Resuming triggers.

[10:58:11.716]     INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.

[10:58:11.719]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:58:35.861]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738284 events.

[10:58:41.835]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738284, pixels seen in all events: 11393253

[10:58:41.934]     INFO: Resuming triggers.

[10:58:49.429]     INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.

[10:58:49.432]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:59:13.556]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738326 events.

[10:59:19.633]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738326, pixels seen in all events: 11392527

[10:59:19.734]     INFO: Resuming triggers.

[10:59:27.231]     INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.

[10:59:27.235]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[10:59:51.378]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 738640 events.

[10:59:57.346]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 738640, pixels seen in all events: 11388172

[10:59:57.443]     INFO: Resuming triggers.

[11:00:00.327]     INFO: data taking finished, elapsed time: 100 seconds.

[11:00:00.522]    DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer

[11:00:09.932]    DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 284019 events.

[11:00:12.234]    DEBUG: <PixTestXray.cc/processData:L823>  # events read: 284019, pixels seen in all events: 4381360

[11:00:12.260]     INFO: PixTest::       pg_setup set to default.

[11:00:12.263]     INFO: PixTestXray::doPhRun() done

[11:00:12.263]    DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor

[11:00:12.411]     INFO: enter test to run

[11:00:26.701]     INFO:   test: HighRate no parameter change

[11:00:26.701]     INFO:   running: highrate

[11:00:26.701]    DEBUG: <PixTestHighRate.cc/setParameter:L68>   setting fParTriggerFrequency -> 20

[11:00:26.701]    DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()

[11:00:26.701]    DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)

[11:00:26.702]    DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan

[11:00:26.703]     INFO:    ----------------------------------------------------------------------

[11:00:26.703]     INFO:    PixTestHighRate::calDelScan() ntrig = 10, vcal = 200

[11:00:26.703]     INFO:    ----------------------------------------------------------------------

[11:00:26.843]     INFO: Expecting 768 events.

[11:00:27.977]     INFO: 768 events read in total (419ms).

[11:00:27.977]     INFO: Test took 1269ms.

[11:00:27.985]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:00:28.780]     INFO: Expecting 41600 events.

[11:00:31.768]     INFO: 41600 events read in total (2461ms).

[11:00:31.769]     INFO: Test took 3784ms.

[11:00:31.797]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:00:31.797]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 251088

[11:00:31.797]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15

[11:00:31.797]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:00:31.813]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:00:32.539]     INFO: Expecting 41600 events.

[11:00:35.654]     INFO: 41600 events read in total (2588ms).

[11:00:35.655]     INFO: Test took 3842ms.

[11:00:35.684]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:00:35.684]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 259931

[11:00:35.684]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15

[11:00:35.684]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:00:35.699]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:00:36.420]     INFO: Expecting 41600 events.

[11:00:39.589]     INFO: 41600 events read in total (2642ms).

[11:00:39.590]     INFO: Test took 3891ms.

[11:00:39.619]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:00:39.619]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 262891

[11:00:39.619]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15

[11:00:39.619]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:00:39.634]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:00:40.352]     INFO: Expecting 41600 events.

[11:00:43.477]     INFO: 41600 events read in total (2598ms).

[11:00:43.478]     INFO: Test took 3844ms.

[11:00:43.506]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:00:43.506]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 262507

[11:00:43.506]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15

[11:00:43.507]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:00:43.521]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:00:44.240]     INFO: Expecting 41600 events.

[11:00:47.402]     INFO: 41600 events read in total (2635ms).

[11:00:47.403]     INFO: Test took 3882ms.

[11:00:47.430]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:00:47.430]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 262391

[11:00:47.430]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15

[11:00:47.431]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:00:47.447]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:00:48.165]     INFO: Expecting 41600 events.

[11:00:51.348]     INFO: 41600 events read in total (2657ms).

[11:00:51.349]     INFO: Test took 3902ms.

[11:00:51.377]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:00:51.377]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 263219

[11:00:51.377]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15

[11:00:51.378]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:00:51.394]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:00:52.109]     INFO: Expecting 41600 events.

[11:00:55.287]     INFO: 41600 events read in total (2651ms).

[11:00:55.288]     INFO: Test took 3894ms.

[11:00:55.316]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:00:55.316]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 263163

[11:00:55.316]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15

[11:00:55.316]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:00:55.332]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:00:56.052]     INFO: Expecting 41600 events.

[11:00:59.238]     INFO: 41600 events read in total (2659ms).

[11:00:59.239]     INFO: Test took 3907ms.

[11:00:59.267]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:00:59.267]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 263141

[11:00:59.267]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15

[11:00:59.267]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:00:59.284]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:00.003]     INFO: Expecting 41600 events.

[11:01:03.183]     INFO: 41600 events read in total (2654ms).

[11:01:03.184]     INFO: Test took 3900ms.

[11:01:03.213]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:01:03.213]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 262900

[11:01:03.213]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15

[11:01:03.213]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:01:03.228]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:03.948]     INFO: Expecting 41600 events.

[11:01:07.142]     INFO: 41600 events read in total (2667ms).

[11:01:07.143]     INFO: Test took 3915ms.

[11:01:07.171]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:01:07.171]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 262507

[11:01:07.171]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15

[11:01:07.171]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:01:07.188]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:07.904]     INFO: Expecting 41600 events.

[11:01:11.082]     INFO: 41600 events read in total (2651ms).

[11:01:11.083]     INFO: Test took 3895ms.

[11:01:11.110]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:01:11.110]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 262521

[11:01:11.110]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15

[11:01:11.111]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:01:11.127]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:11.848]     INFO: Expecting 41600 events.

[11:01:15.012]     INFO: 41600 events read in total (2637ms).

[11:01:15.013]     INFO: Test took 3886ms.

[11:01:15.041]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:01:15.041]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 263469

[11:01:15.041]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15

[11:01:15.041]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:01:15.056]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:15.773]     INFO: Expecting 41600 events.

[11:01:18.964]     INFO: 41600 events read in total (2664ms).

[11:01:18.965]     INFO: Test took 3909ms.

[11:01:18.993]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:01:18.993]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 262659

[11:01:18.993]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15

[11:01:18.994]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:01:19.010]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:19.729]     INFO: Expecting 41600 events.

[11:01:22.911]     INFO: 41600 events read in total (2656ms).

[11:01:22.912]     INFO: Test took 3902ms.

[11:01:22.940]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:01:22.940]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 263209

[11:01:22.940]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15

[11:01:22.941]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:01:22.956]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:23.674]     INFO: Expecting 41600 events.

[11:01:26.858]     INFO: 41600 events read in total (2657ms).

[11:01:26.859]     INFO: Test took 3903ms.

[11:01:26.887]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:01:26.888]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 262775

[11:01:26.888]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15

[11:01:26.888]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:01:26.904]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:27.622]     INFO: Expecting 41600 events.

[11:01:30.806]     INFO: 41600 events read in total (2657ms).

[11:01:30.807]     INFO: Test took 3903ms.

[11:01:30.835]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:01:30.835]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 262682

[11:01:30.835]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15

[11:01:30.835]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:01:30.852]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:31.569]     INFO: Expecting 41600 events.

[11:01:34.742]     INFO: 41600 events read in total (2646ms).

[11:01:34.743]     INFO: Test took 3891ms.

[11:01:34.772]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:01:34.772]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 262310

[11:01:34.772]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15

[11:01:34.772]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:01:34.788]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:35.506]     INFO: Expecting 41600 events.

[11:01:38.677]     INFO: 41600 events read in total (2644ms).

[11:01:38.678]     INFO: Test took 3890ms.

[11:01:38.706]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:01:38.706]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 262552

[11:01:38.706]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15

[11:01:38.706]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:01:38.722]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:39.439]     INFO: Expecting 41600 events.

[11:01:42.602]     INFO: 41600 events read in total (2636ms).

[11:01:42.603]     INFO: Test took 3881ms.

[11:01:42.631]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:01:42.632]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 262190

[11:01:42.632]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15

[11:01:42.632]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:01:42.649]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:43.365]     INFO: Expecting 41600 events.

[11:01:46.394]     INFO: 41600 events read in total (2502ms).

[11:01:46.395]     INFO: Test took 3746ms.

[11:01:46.423]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:01:46.423]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 254178

[11:01:46.423]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15

[11:01:46.423]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:01:46.778]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc  0: caldel = 178 eff = 0.999591

[11:01:46.778]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc  1: caldel = 203 eff = 0.999712

[11:01:46.778]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc  2: caldel = 169 eff = 0.99988

[11:01:46.778]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc  3: caldel = 159 eff = 0.999591

[11:01:46.779]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc  4: caldel = 176 eff = 0.999663

[11:01:46.779]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc  5: caldel = 217 eff = 0.989736

[11:01:46.779]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc  6: caldel = 141 eff = 0.999712

[11:01:46.779]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc  7: caldel = 174 eff = 0.982933

[11:01:46.780]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc  8: caldel = 170 eff = 0.99988

[11:01:46.780]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc  9: caldel = 183 eff = 0.999663

[11:01:46.780]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 191 eff = 0.999688

[11:01:46.780]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 196 eff = 0.999471

[11:01:46.781]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 190 eff = 0.999399

[11:01:46.781]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 209 eff = 0.999832

[11:01:46.781]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 159 eff = 0.999952

[11:01:46.781]    DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 155 eff = 0.999976

[11:01:46.785]    DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor

[11:01:46.805]     INFO: enter test to run

[11:01:52.796]     INFO:   test: HighRate no parameter change

[11:01:52.797]     INFO:   running: highrate

[11:01:52.797]    DEBUG: <PixTestHighRate.cc/setParameter:L68>   setting fParTriggerFrequency -> 20

[11:01:52.797]    DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()

[11:01:52.797]    DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)

[11:01:52.798]    DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive

[11:01:52.798]     INFO:    ----------------------------------------------------------------------

[11:01:52.798]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[11:01:52.798]     INFO:    ----------------------------------------------------------------------

[11:01:52.798]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: clk: 4

[11:01:52.798]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: ctr: 4

[11:01:52.798]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: sda: 19

[11:01:52.798]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: tin: 9

[11:01:52.798]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: level: 15

[11:01:52.798]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: triggerdelay: 0

[11:01:52.798]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: clk: 4

[11:01:52.798]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: ctr: 4

[11:01:52.798]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: sda: 19

[11:01:52.798]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: tin: 9

[11:01:52.799]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: level: 15

[11:01:52.799]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: triggerdelay: 20

[11:01:52.805]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:01:53.409]     INFO: Expecting 208000 events.

[11:02:05.047]     INFO: 208000 events read in total (11111ms).

[11:02:05.049]     INFO: Test took 12244ms.

[11:02:05.162]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:02:05.162]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 1043479

[11:02:05.162]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15

[11:02:05.163]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:02:05.404]     INFO: number of dead pixels (per ROC):     1    1    0    0    0    0    0   69    0    0    0    1    1    0    0    0

[11:02:05.404]     INFO: number of red-efficiency pixels:    45   27   78   86  120  242   94  142   72   89   83   63   84   53   17   15

[11:02:05.404]     INFO: number of X-ray hits detected:    52754 34370 52264 81344 84962 88200 80692 52202 56917 81925 82428 71095 75502 44295 18502 19540

[11:02:05.404]     INFO: number of triggers sent (total per ROC):  208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000

[11:02:05.404]     INFO: number of Vcal hits detected:  207906 207924 207921 207914 207880 205954 207903 204426 207926 207908 207916 207885 207817 207947 207982 207985

[11:02:05.404]     INFO: Vcal hit fiducial efficiency (%):  100.0 100.0 100.0 100.0 99.9 99.0 100.0 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0

[11:02:05.404]     INFO: Vcal hit overall efficiency (%):  100.0 100.0 100.0 100.0 99.9 99.0 100.0 98.3 100.0 100.0 100.0 99.9 99.9 100.0 100.0 100.0

[11:02:05.404]     INFO: X-ray hit rate [MHz/cm2]:  15.5 10.1 15.3 23.8 24.9 25.9 23.7 15.3 16.7 24.0 24.2 20.8 22.1 13.0 5.4 5.7

[11:02:05.404]     INFO: PixTestHighRate::doXPixelAlive() done

[11:02:05.454]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: clk: 4

[11:02:05.454]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: ctr: 4

[11:02:05.454]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: sda: 19

[11:02:05.454]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: tin: 9

[11:02:05.454]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: level: 15

[11:02:05.454]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: triggerdelay: 0

[11:02:05.455]     INFO: PixTest::       pg_setup set to default.

[11:02:05.455]    DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor

[11:02:05.470]     INFO: enter test to run

[11:02:15.596]     INFO:   test: HighRate no parameter change

[11:02:15.596]     INFO:   running: highrate

[11:02:15.596]    DEBUG: <PixTestHighRate.cc/setParameter:L68>   setting fParTriggerFrequency -> 20

[11:02:15.596]    DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()

[11:02:15.597]    DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive

[11:02:15.598]     INFO:    ----------------------------------------------------------------------

[11:02:15.598]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[11:02:15.598]     INFO:    ----------------------------------------------------------------------

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: clk: 4

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: ctr: 4

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: sda: 19

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: tin: 9

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: level: 15

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: triggerdelay: 0

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: clk: 4

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: ctr: 4

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: sda: 19

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: tin: 9

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: level: 15

[11:02:15.598]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: triggerdelay: 20

[11:02:15.605]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:02:16.207]     INFO: Expecting 208000 events.

[11:02:29.260]     INFO: 208000 events read in total (12526ms).

[11:02:29.265]     INFO: Test took 13660ms.

[11:02:29.509]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:02:29.509]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 2175894

[11:02:29.509]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15

[11:02:29.509]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:02:29.790]     INFO: number of dead pixels (per ROC):     1    1    0    0    0   39    0   69    0    0    0    1    2    0    0    0

[11:02:29.790]     INFO: number of red-efficiency pixels:   152   93  216  323  326  427  235  237  162  322  314  203  252  161   41   52

[11:02:29.790]     INFO: number of X-ray hits detected:    112951 74286 113067 175876 184142 188814 174540 113367 123646 177178 177500 154044 161671 96602 39371 42392

[11:02:29.790]     INFO: number of triggers sent (total per ROC):  208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000

[11:02:29.790]     INFO: number of Vcal hits detected:  207793 207852 207770 207652 207657 201761 207754 204317 207834 207644 207664 207744 207637 207833 207959 207947

[11:02:29.790]     INFO: Vcal hit fiducial efficiency (%):  99.9 100.0 99.9 99.9 99.8 97.8 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0

[11:02:29.791]     INFO: Vcal hit overall efficiency (%):  99.9 99.9 99.9 99.8 99.8 97.0 99.9 98.2 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0

[11:02:29.791]     INFO: X-ray hit rate [MHz/cm2]:  33.1 21.8 33.1 51.6 54.0 55.3 51.2 33.2 36.2 51.9 52.0 45.2 47.4 28.3 11.5 12.4

[11:02:29.791]     INFO: PixTestHighRate::doXPixelAlive() done

[11:02:29.836]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: clk: 4

[11:02:29.836]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: ctr: 4

[11:02:29.836]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: sda: 19

[11:02:29.836]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: tin: 9

[11:02:29.836]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: level: 15

[11:02:29.836]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: triggerdelay: 0

[11:02:29.836]     INFO: PixTest::       pg_setup set to default.

[11:02:29.836]    DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor

[11:02:29.848]     INFO: enter test to run

[11:02:37.908]     INFO:   test: HighRate no parameter change

[11:02:37.908]     INFO:   running: highrate

[11:02:37.908]    DEBUG: <PixTestHighRate.cc/setParameter:L68>   setting fParTriggerFrequency -> 20

[11:02:37.908]    DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()

[11:02:37.908]    DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)

[11:02:37.909]    DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive

[11:02:37.909]     INFO:    ----------------------------------------------------------------------

[11:02:37.909]     INFO:    PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200

[11:02:37.909]     INFO:    ----------------------------------------------------------------------

[11:02:37.910]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: clk: 4

[11:02:37.910]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: ctr: 4

[11:02:37.910]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: sda: 19

[11:02:37.910]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: tin: 9

[11:02:37.910]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: level: 15

[11:02:37.910]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464>  old set: triggerdelay: 0

[11:02:37.910]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: clk: 4

[11:02:37.910]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: ctr: 4

[11:02:37.910]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: sda: 19

[11:02:37.910]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: tin: 9

[11:02:37.910]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: level: 15

[11:02:37.910]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480>  setting: triggerdelay: 20

[11:02:37.916]    DEBUG: <PixTest.cc/efficiencyMaps:L396>       attempt #0

[11:02:38.521]     INFO: Expecting 208000 events.

[11:02:53.116]     INFO: 208000 events read in total (14068ms).

[11:02:53.122]     INFO: Test took 15206ms.

[11:02:53.482]     INFO: Fetched DAQ statistics. Counters are being reset now.

[11:02:53.482]    DEBUG: <PixTest.cc/efficiencyMaps:L407>  eff result size = 3283824

[11:02:53.482]    DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15

[11:02:53.482]    DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector

[11:02:53.804]     INFO: number of dead pixels (per ROC):     1    1    0    0    0   39    0   69    0    0    0    1    2    0    0    0

[11:02:53.804]     INFO: number of red-efficiency pixels:   361  168  429  750  765  726  524  483  312  744  790  442  492  335   68   73

[11:02:53.804]     INFO: number of X-ray hits detected:    172146 113052 172193 268427 280503 287443 266532 172790 189195 269024 271496 234570 247246 147120 60569 65071

[11:02:53.804]     INFO: number of triggers sent (total per ROC):  208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000

[11:02:53.804]     INFO: number of Vcal hits detected:  207550 207770 207497 207127 207115 201372 207393 204023 207659 207125 207062 207475 207347 207611 207930 207926

[11:02:53.804]     INFO: Vcal hit fiducial efficiency (%):  99.8 99.9 99.8 99.6 99.6 97.7 99.7 99.8 99.8 99.6 99.6 99.8 99.8 99.8 100.0 100.0

[11:02:53.804]     INFO: Vcal hit overall efficiency (%):  99.8 99.9 99.8 99.6 99.6 96.8 99.7 98.1 99.8 99.6 99.5 99.7 99.7 99.8 100.0 100.0

[11:02:53.804]     INFO: X-ray hit rate [MHz/cm2]:  50.5 33.1 50.5 78.7 82.2 84.3 78.1 50.6 55.5 78.9 79.6 68.8 72.5 43.1 17.8 19.1

[11:02:53.804]     INFO: PixTestHighRate::doXPixelAlive() done

[11:02:53.853]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: clk: 4

[11:02:53.853]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: ctr: 4

[11:02:53.853]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: sda: 19

[11:02:53.854]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: tin: 9

[11:02:53.854]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: level: 15

[11:02:53.854]    DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599>  resetting: triggerdelay: 0

[11:02:53.854]     INFO: PixTest::       pg_setup set to default.

[11:02:53.854]    DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor

[11:02:53.869]     INFO: enter test to run

[11:02:58.716]     INFO:   test: exit no parameter change

[11:02:58.717]    DEBUG: <pXar.cc/main:L340> Final Analog Current: 419.6mA

[11:02:58.718]    DEBUG: <pXar.cc/main:L341> Final Digital Current: 476.7mA

[11:02:58.718]    DEBUG: <pXar.cc/main:L342> Final Module Temperature: 17.0 C

[11:02:58.718]    DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries

[11:02:59.092]    QUIET: Connection to board 33 closed.

[11:02:59.093]     INFO: pXar: this is the end, my friend

[11:02:59.094]    DEBUG: <PixSetup.cc/~PixSetup:L68> PixSetup free fPxarMemory
MoReWeb-v0.5.1-904-gd94b9f2 on branch master