[14:25:50.426] INFO: *** Welcome to pxar ***
[14:25:50.426] INFO: *** Today: 2017/02/02
[14:25:51.337] INFO: *** Version: v1.9.0-825-g6bc29
[14:25:51.337] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-5-23_FPIXTest-17C-FNAL-170201-1320-150V_2017-02-01_13h28m_1485977315/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-5-23_FPIXTest-17C-FNAL-170201-1320-150V_2017-02-01_13h28m_1485977315/000_FPIXTest_p17//dacParameters35_C15.dat
[14:25:51.405] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-5-23_FPIXTest-17C-FNAL-170201-1320-150V_2017-02-01_13h28m_1485977315/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-5-23_FPIXTest-17C-FNAL-170201-1320-150V_2017-02-01_13h28m_1485977315/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:25:51.410] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-5-23_FPIXTest-17C-FNAL-170201-1320-150V_2017-02-01_13h28m_1485977315/000_FPIXTest_p17//defaultMaskFile.dat
[14:25:51.425] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-5-23_FPIXTest-17C-FNAL-170201-1320-150V_2017-02-01_13h28m_1485977315/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-5-23_FPIXTest-17C-FNAL-170201-1320-150V_2017-02-01_13h28m_1485977315/000_FPIXTest_p17//trimParameters35_C15.dat
[14:25:51.591] INFO: clk: 4
[14:25:51.591] INFO: ctr: 4
[14:25:51.591] INFO: sda: 19
[14:25:51.591] INFO: tin: 9
[14:25:51.591] INFO: level: 15
[14:25:51.591] INFO: triggerdelay: 0
[14:25:51.591] QUIET: Instanciating API for pxar v1.9.0+825~g6bc290c
[14:25:51.591] INFO: Log level: DEBUG
[14:25:51.610] QUIET: Connection to board DTB_WREKRL opened.
[14:25:51.614] 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:
------------------------------------------------------
[14:25:51.616] INFO: RPC call hashes of host and DTB match: 398089610
[14:25:53.149] INFO: DUT info:
[14:25:53.149] INFO: The DUT currently contains the following objects:
[14:25:53.149] INFO: 2 TBM Cores tbm08c (2 ON)
[14:25:53.149] INFO: TBM Core alpha (0): 7 registers set
[14:25:53.149] INFO: TBM Core beta (1): 7 registers set
[14:25:53.149] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:25:53.149] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.149] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.149] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.149] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.149] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.149] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.149] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.150] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.150] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.150] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.150] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.150] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.150] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.150] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.150] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.150] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB<-
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB2<-
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 222
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> plwidth: 35
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB3<-
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> vcals: 250
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->BB4<-
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> savecaldelscan: checkbox(0)
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 100
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> cals: 1
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> caldello: 80
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> caldelhi: 200
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> caldelstep: 10
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomplo: 70
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> vthrcomphi: 130
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompstep: 5
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> noisypixels: 10
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 255
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> cut: 0.5
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Cmd<-
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacDacScan<-
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox
[14:25:53.197] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dac1: caldel
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dac1lo: 0
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dac1hi: 255
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dac2: vthrcomp
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dac2lo: 0
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dac2hi: 255
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->DacScan<-
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(1)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> allpixels: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> unmasked: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dac: vcal
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 255
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->GainPedestal<-
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> showfits: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> extended: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dumphists: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> vcalstep: 10
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> measure: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> fit: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> save: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->HighRate<-
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixels: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> trimhotpixelthr: 200
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> runsecondshotpixels: 10
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> savetrimbits: checkbox(1)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> maskuntrimmable: checkbox(1)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> caldelscan: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> xpixelalive: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> xnoisemaps: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 100
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: 20
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> rundaq: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 20
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 2
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> triggerdelay: 20
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->IV<-
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> port: /dev/FIXME
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> voltagestart: 0
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> voltagestop: 600
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> voltagestep: 5
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> delay: 1
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> compliance(ua): 100
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PhOptimization<-
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> safetymarginlow: 20
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> saturationvcal: 100
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> quantilesaturation: 0.98
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->PixelAlive<-
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> phmap: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 200
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> alivetest: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> masktest: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> addressdecodingtest: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Pretest<-
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> programroc: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> checkidig: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> targetia: 24
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> iterations: 100
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> settimings: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> findtiming: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> findworkingpixel: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> setvthrcompcaldel: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> pix: 11,20
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 250
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> deltavthrcomp: 50
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> fraccaldel: 0.5
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 5
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> savedacs: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Readback<-
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> calibratevd: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> calibrateva: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> calibrateia: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> readbackvbg: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> getcalibratedvbg: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> usecalvd: checkbox(1)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> usecalva: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> setvana: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Scurves<-
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> adjustvcal: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dumpall: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dumpproblematic: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dumpoutputfile: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 50
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dac: Vcal
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> daclo: 0
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dachi: 200
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> dacs/step: -1
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig/step: -1
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> scurves: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Timing<-
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> targetclk: 4
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 10000
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> clocksdascan: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> notokenpass: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> ignorereadback: checkbox(0)
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> phasescan: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> levelscan: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> tbmphasescan: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> rocdelayscan: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> timingtest: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> saveparameters: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Trim<-
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> trim: button
[14:25:53.198] DEBUG: <PixTestParameters.cc/dump:L107> ntrig: 8
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> vcal: 35
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> trimbits: button
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L104> PixTestParameters: ->Xray<-
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> maskhotpixels: button
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> savemaskfile: checkbox(0)
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> maskfilename: default
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> source: Ag
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> phrun: button
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> runseconds: 100
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> trgfrequency(khz): 100
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> ratescan: button
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmin: 10
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> vthrcompmax: 80
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> stepseconds: 5
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> delaytbm: checkbox
[14:25:53.199] DEBUG: <PixTestParameters.cc/dump:L107> filltree: checkbox
[14:25:53.201] DEBUG: <PixSetup.cc/init:L81> PixSetup init start; getCurrentRSS() = 29057024
[14:25:53.201] DEBUG: <PixSetup.cc/init:L87> fPixTestParameters = 0xcff220
[14:25:53.201] DEBUG: <PixSetup.cc/init:L88> fConfigParameters = 0xc25310
[14:25:53.201] DEBUG: <PixSetup.cc/init:L89> fPxarMemory = 0x7f35fdd94010
[14:25:53.201] DEBUG: <PixSetup.cc/init:L90> fPxarMemHi = 0x7f3603fff510
[14:25:53.201] DEBUG: <PixSetup.cc/init:L106> PixSetup init done; getCurrentRSS() = 29122560 fPxarMemory = 0x7f35fdd94010
[14:25:53.202] DEBUG: <pXar.cc/main:L223> Initial Analog Current: 387.5mA
[14:25:53.204] DEBUG: <pXar.cc/main:L224> Initial Digital Current: 466.3mA
[14:25:53.204] DEBUG: <pXar.cc/main:L225> Initial Module Temperature: 1.3 C
[14:25:53.204] DEBUG: <PixTestFactory.cc/PixTestFactory:L53> PixTestFactory::PixTestFactory()
[14:25:53.609] INFO: enter 'restricted' command line mode
[14:25:53.609] INFO: enter test to run
[14:29:15.735] INFO: test: PixelAlive no parameter change
[14:29:15.735] INFO: running: pixelalive
[14:29:15.736] DEBUG: <PixTestAlive.cc/init:L83> PixTestAlive::init()
[14:29:15.757] DEBUG: <PixTestAlive.cc/PixTestAlive:L21> PixTestAlive ctor(PixSetup &a, string, TGTab *)
[14:29:15.757] DEBUG: <PixTestAlive.cc/runCommand:L62> running command: alivetest
[14:29:15.785] INFO: ----------------------------------------------------------------------
[14:29:15.785] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:29:15.785] INFO: ----------------------------------------------------------------------
[14:29:15.789] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:29:16.116] INFO: Expecting 41600 events.
[14:29:20.505] INFO: 41600 events read in total (3670ms).
[14:29:20.672] INFO: Test took 4883ms.
[14:29:20.687] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:29:20.687] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 66543
[14:29:20.687] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists PixelAlive_C0 .. PixelAlive_C15
[14:29:21.200] INFO: PixTestAlive::aliveTest() done
[14:29:21.201] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 10 2 2 0 0 0 0 0 2 0
[14:29:21.201] DEBUG: <PixTestAlive.cc/aliveTest:L199> number of red-efficiency pixels: 0 1 0 0 0 0 10 2 2 0 0 0 0 0 2 0
[14:29:21.201] DEBUG: <PixTestAlive.cc/~PixTestAlive:L115> PixTestAlive dtor
[14:29:21.347] INFO: enter test to run
[14:30:07.263] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:30:07.263] DEBUG: <PixTestParameters.cc/setTestParameter:L119> PixTestParameters: ->Xray<-
[14:30:07.263] DEBUG: <PixTestParameters.cc/setTestParameter:L124> setting source to new value DCLowRate
[14:30:07.263] INFO: running: xray
[14:30:07.263] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:30:07.263] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:30:07.263] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-5-23_FPIXTest-17C-FNAL-170201-1320-150V_2017-02-01_13h28m_1485977315/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-5-23_FPIXTest-17C-FNAL-170201-1320-150V_2017-02-01_13h28m_1485977315/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:30:07.526] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[14:30:07.526] INFO: ----------------------------------------------------------------------
[14:30:07.526] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:30:07.526] INFO: ----------------------------------------------------------------------
[14:30:08.498] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:30:20.038] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:30:20.042] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:30:45.704] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1136993 events.
[14:30:49.616] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1136993, pixels seen in all events: 6607706
[14:30:49.720] INFO: Resuming triggers.
[14:31:01.256] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[14:31:01.260] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:31:26.994] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1136550 events.
[14:31:30.938] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1136550, pixels seen in all events: 6612566
[14:31:30.968] INFO: Resuming triggers.
[14:31:42.504] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:31:42.508] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:32:08.095] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1136500 events.
[14:32:12.028] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1136500, pixels seen in all events: 6613427
[14:32:12.151] INFO: Resuming triggers.
[14:32:23.690] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[14:32:23.697] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:32:49.753] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1136710 events.
[14:32:53.872] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1136710, pixels seen in all events: 6610889
[14:32:53.999] INFO: Resuming triggers.
[14:33:05.536] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[14:33:05.545] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:33:31.708] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1136559 events.
[14:33:35.607] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1136559, pixels seen in all events: 6612926
[14:33:35.691] INFO: Resuming triggers.
[14:33:47.224] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[14:33:47.228] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:34:13.372] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1136266 events.
[14:34:17.203] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1136266, pixels seen in all events: 6615679
[14:34:17.282] INFO: Resuming triggers.
[14:34:28.815] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[14:34:28.819] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:34:55.090] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1136203 events.
[14:34:59.097] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1136203, pixels seen in all events: 6616676
[14:34:59.178] INFO: Resuming triggers.
[14:35:10.711] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[14:35:10.715] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:35:36.768] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 1136202 events.
[14:35:40.319] DEBUG: <PixTestXray.cc/processData:L823> # events read: 1136202, pixels seen in all events: 6617335
[14:35:40.352] INFO: Resuming triggers.
[14:35:48.435] INFO: data taking finished, elapsed time: 100 seconds.
[14:35:48.631] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:36:06.736] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 796276 events.
[14:36:09.234] DEBUG: <PixTestXray.cc/processData:L823> # events read: 796276, pixels seen in all events: 4635273
[14:36:09.257] INFO: PixTest:: pg_setup set to default.
[14:36:09.260] INFO: PixTestXray::doPhRun() done
[14:36:09.260] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:36:09.465] INFO: enter test to run
[14:36:34.285] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:36:34.285] DEBUG: <PixTestParameters.cc/setTestParameter:L119> PixTestParameters: ->Xray<-
[14:36:34.285] DEBUG: <PixTestParameters.cc/setTestParameter:L124> setting source to new value DCHighRate
[14:36:34.285] INFO: running: xray
[14:36:34.285] DEBUG: <PixTestXray.cc/init:L135> PixTestXray::init()
[14:36:34.285] DEBUG: <PixTestXray.cc/PixTestXray:L28> PixTestXray ctor(PixSetup &a, string, TGTab *)
[14:36:34.286] DEBUG: <PixTestXray.cc/runCommand:L109> running command: phrun
[14:36:34.286] INFO: ----------------------------------------------------------------------
[14:36:34.286] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:36:34.286] INFO: ----------------------------------------------------------------------
[14:36:35.249] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:36:42.269] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:36:42.273] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:37:06.222] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691658 events.
[14:37:12.747] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691658, pixels seen in all events: 11952125
[14:37:12.829] INFO: Resuming triggers.
[14:37:19.853] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[14:37:19.856] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:37:43.752] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691898 events.
[14:37:50.230] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691898, pixels seen in all events: 11949216
[14:37:50.314] INFO: Resuming triggers.
[14:37:57.332] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:37:57.335] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:38:21.281] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691340 events.
[14:38:27.942] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691340, pixels seen in all events: 11955642
[14:38:28.025] INFO: Resuming triggers.
[14:38:35.043] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[14:38:35.046] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:38:58.851] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691348 events.
[14:39:05.283] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691348, pixels seen in all events: 11956603
[14:39:05.364] INFO: Resuming triggers.
[14:39:12.381] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:39:12.384] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:39:36.269] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691233 events.
[14:39:42.888] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691233, pixels seen in all events: 11956943
[14:39:42.968] INFO: Resuming triggers.
[14:39:49.983] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[14:39:49.987] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:40:13.910] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691026 events.
[14:40:20.200] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691026, pixels seen in all events: 11959944
[14:40:20.282] INFO: Resuming triggers.
[14:40:27.299] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[14:40:27.302] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:40:51.157] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691272 events.
[14:40:57.701] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691272, pixels seen in all events: 11956733
[14:40:57.786] INFO: Resuming triggers.
[14:41:04.802] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[14:41:04.805] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:41:28.815] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691102 events.
[14:41:35.363] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691102, pixels seen in all events: 11958498
[14:41:35.446] INFO: Resuming triggers.
[14:41:42.462] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[14:41:42.466] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:42:06.391] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691219 events.
[14:42:12.737] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691219, pixels seen in all events: 11957430
[14:42:12.820] INFO: Resuming triggers.
[14:42:19.840] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:42:19.843] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:42:43.899] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691474 events.
[14:42:50.300] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691474, pixels seen in all events: 11954693
[14:42:50.383] INFO: Resuming triggers.
[14:42:57.396] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[14:42:57.399] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:43:21.472] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 690864 events.
[14:43:28.558] DEBUG: <PixTestXray.cc/processData:L823> # events read: 690864, pixels seen in all events: 11961622
[14:43:28.642] INFO: Resuming triggers.
[14:43:35.660] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[14:43:35.663] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:43:59.634] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691387 events.
[14:44:06.584] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691387, pixels seen in all events: 11956053
[14:44:06.670] INFO: Resuming triggers.
[14:44:13.686] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[14:44:13.690] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:44:38.302] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691200 events.
[14:44:45.216] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691200, pixels seen in all events: 11958212
[14:44:45.298] INFO: Resuming triggers.
[14:44:52.320] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[14:44:52.323] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:45:15.995] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 691757 events.
[14:45:22.787] DEBUG: <PixTestXray.cc/processData:L823> # events read: 691757, pixels seen in all events: 11951247
[14:45:22.869] INFO: Resuming triggers.
[14:45:24.966] INFO: data taking finished, elapsed time: 100 seconds.
[14:45:25.162] DEBUG: <PixTestXray.cc/processData:L754> Getting Event Buffer
[14:45:32.447] DEBUG: <PixTestXray.cc/processData:L772> Processing Data: 206567 events.
[14:45:34.489] DEBUG: <PixTestXray.cc/processData:L823> # events read: 206567, pixels seen in all events: 3573530
[14:45:34.504] INFO: PixTest:: pg_setup set to default.
[14:45:34.507] INFO: PixTestXray::doPhRun() done
[14:45:34.507] DEBUG: <PixTestXray.cc/~PixTestXray:L218> PixTestXray dtor
[14:45:34.663] INFO: enter test to run
[14:47:48.536] INFO: test: HighRate no parameter change
[14:47:48.536] INFO: running: highrate
[14:47:48.536] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:47:48.536] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:47:48.536] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:47:48.552] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: caldelscan
[14:47:48.552] INFO: ----------------------------------------------------------------------
[14:47:48.552] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:47:48.552] INFO: ----------------------------------------------------------------------
[14:47:48.695] INFO: Expecting 768 events.
[14:47:49.829] INFO: 768 events read in total (418ms).
[14:47:49.830] INFO: Test took 1269ms.
[14:47:49.836] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:50.633] INFO: Expecting 41600 events.
[14:47:53.729] INFO: 41600 events read in total (2570ms).
[14:47:53.730] INFO: Test took 3893ms.
[14:47:53.762] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:53.762] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 289260
[14:47:53.762] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step0_C0 .. HR_xeff_CalDelScan_step0_C15
[14:47:53.762] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:47:53.778] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:54.492] INFO: Expecting 41600 events.
[14:47:57.741] INFO: 41600 events read in total (2722ms).
[14:47:57.742] INFO: Test took 3964ms.
[14:47:57.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:47:57.775] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 295867
[14:47:57.775] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step1_C0 .. HR_xeff_CalDelScan_step1_C15
[14:47:57.775] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:47:57.793] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:47:58.504] INFO: Expecting 41600 events.
[14:48:01.793] INFO: 41600 events read in total (2762ms).
[14:48:01.794] INFO: Test took 4001ms.
[14:48:01.828] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:01.828] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 298533
[14:48:01.828] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step2_C0 .. HR_xeff_CalDelScan_step2_C15
[14:48:01.828] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:01.844] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:02.544] INFO: Expecting 41600 events.
[14:48:05.776] INFO: 41600 events read in total (2706ms).
[14:48:05.777] INFO: Test took 3933ms.
[14:48:05.810] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:05.810] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 298561
[14:48:05.810] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step3_C0 .. HR_xeff_CalDelScan_step3_C15
[14:48:05.810] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:05.826] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:06.535] INFO: Expecting 41600 events.
[14:48:09.750] INFO: 41600 events read in total (2688ms).
[14:48:09.751] INFO: Test took 3925ms.
[14:48:09.783] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:09.783] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 298500
[14:48:09.783] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step4_C0 .. HR_xeff_CalDelScan_step4_C15
[14:48:09.783] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:09.801] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:10.510] INFO: Expecting 41600 events.
[14:48:13.723] INFO: 41600 events read in total (2686ms).
[14:48:13.724] INFO: Test took 3923ms.
[14:48:13.757] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:13.757] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 298988
[14:48:13.757] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step5_C0 .. HR_xeff_CalDelScan_step5_C15
[14:48:13.757] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:13.773] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:14.485] INFO: Expecting 41600 events.
[14:48:17.707] INFO: 41600 events read in total (2695ms).
[14:48:17.708] INFO: Test took 3935ms.
[14:48:17.741] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:17.741] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 299173
[14:48:17.741] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step6_C0 .. HR_xeff_CalDelScan_step6_C15
[14:48:17.741] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:17.758] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:18.469] INFO: Expecting 41600 events.
[14:48:21.692] INFO: 41600 events read in total (2696ms).
[14:48:21.693] INFO: Test took 3935ms.
[14:48:21.724] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:21.724] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 297926
[14:48:21.724] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step7_C0 .. HR_xeff_CalDelScan_step7_C15
[14:48:21.724] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:21.743] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:22.453] INFO: Expecting 41600 events.
[14:48:25.678] INFO: 41600 events read in total (2698ms).
[14:48:25.679] INFO: Test took 3936ms.
[14:48:25.712] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:25.712] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 298075
[14:48:25.712] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step8_C0 .. HR_xeff_CalDelScan_step8_C15
[14:48:25.712] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:25.731] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:26.440] INFO: Expecting 41600 events.
[14:48:29.657] INFO: 41600 events read in total (2690ms).
[14:48:29.658] INFO: Test took 3927ms.
[14:48:29.690] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:29.690] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 297932
[14:48:29.690] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step9_C0 .. HR_xeff_CalDelScan_step9_C15
[14:48:29.690] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:29.708] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:30.417] INFO: Expecting 41600 events.
[14:48:33.583] INFO: 41600 events read in total (2638ms).
[14:48:33.583] INFO: Test took 3875ms.
[14:48:33.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:33.616] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 298195
[14:48:33.616] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step10_C0 .. HR_xeff_CalDelScan_step10_C15
[14:48:33.616] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:33.633] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:34.344] INFO: Expecting 41600 events.
[14:48:37.566] INFO: 41600 events read in total (2695ms).
[14:48:37.567] INFO: Test took 3934ms.
[14:48:37.599] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:37.599] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 299082
[14:48:37.599] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step11_C0 .. HR_xeff_CalDelScan_step11_C15
[14:48:37.599] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:37.616] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:38.327] INFO: Expecting 41600 events.
[14:48:41.540] INFO: 41600 events read in total (2686ms).
[14:48:41.541] INFO: Test took 3925ms.
[14:48:41.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:41.573] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 298646
[14:48:41.573] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step12_C0 .. HR_xeff_CalDelScan_step12_C15
[14:48:41.573] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:41.592] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:42.302] INFO: Expecting 41600 events.
[14:48:45.525] INFO: 41600 events read in total (2696ms).
[14:48:45.526] INFO: Test took 3934ms.
[14:48:45.558] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:45.558] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 298355
[14:48:45.558] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step13_C0 .. HR_xeff_CalDelScan_step13_C15
[14:48:45.559] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:45.575] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:46.287] INFO: Expecting 41600 events.
[14:48:49.509] INFO: 41600 events read in total (2695ms).
[14:48:49.510] INFO: Test took 3935ms.
[14:48:49.541] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:49.541] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 298270
[14:48:49.541] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step14_C0 .. HR_xeff_CalDelScan_step14_C15
[14:48:49.541] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:49.559] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:50.271] INFO: Expecting 41600 events.
[14:48:53.489] INFO: 41600 events read in total (2691ms).
[14:48:53.490] INFO: Test took 3931ms.
[14:48:53.522] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:53.522] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 298062
[14:48:53.522] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step15_C0 .. HR_xeff_CalDelScan_step15_C15
[14:48:53.522] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:53.539] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:54.250] INFO: Expecting 41600 events.
[14:48:57.473] INFO: 41600 events read in total (2696ms).
[14:48:57.475] INFO: Test took 3936ms.
[14:48:57.515] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:48:57.515] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 298313
[14:48:57.515] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step16_C0 .. HR_xeff_CalDelScan_step16_C15
[14:48:57.515] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:48:57.534] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:48:58.231] INFO: Expecting 41600 events.
[14:49:01.459] INFO: 41600 events read in total (2701ms).
[14:49:01.460] INFO: Test took 3926ms.
[14:49:01.491] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:01.491] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 298377
[14:49:01.491] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step17_C0 .. HR_xeff_CalDelScan_step17_C15
[14:49:01.491] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:01.510] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:02.221] INFO: Expecting 41600 events.
[14:49:05.441] INFO: 41600 events read in total (2693ms).
[14:49:05.442] INFO: Test took 3932ms.
[14:49:05.473] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:05.474] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 296071
[14:49:05.474] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step18_C0 .. HR_xeff_CalDelScan_step18_C15
[14:49:05.474] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:05.491] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:06.200] INFO: Expecting 41600 events.
[14:49:09.227] INFO: 41600 events read in total (2500ms).
[14:49:09.228] INFO: Test took 3737ms.
[14:49:09.259] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:49:09.259] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 288501
[14:49:09.259] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists HR_xeff_CalDelScan_step19_C0 .. HR_xeff_CalDelScan_step19_C15
[14:49:09.259] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:49:09.598] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 0: caldel = 157 eff = 0.999688
[14:49:09.599] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 1: caldel = 149 eff = 0.999615
[14:49:09.599] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 2: caldel = 168 eff = 0.999712
[14:49:09.599] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 3: caldel = 168 eff = 0.999591
[14:49:09.599] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 4: caldel = 150 eff = 0.999712
[14:49:09.600] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 5: caldel = 146 eff = 0.999471
[14:49:09.600] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 6: caldel = 144 eff = 0.997019
[14:49:09.600] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 7: caldel = 164 eff = 0.999159
[14:49:09.600] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 8: caldel = 202 eff = 0.999279
[14:49:09.601] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 9: caldel = 148 eff = 0.999567
[14:49:09.601] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 10: caldel = 172 eff = 0.999736
[14:49:09.601] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 11: caldel = 151 eff = 0.999832
[14:49:09.601] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 12: caldel = 122 eff = 0.999615
[14:49:09.602] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 13: caldel = 182 eff = 0.99976
[14:49:09.602] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 14: caldel = 166 eff = 0.999519
[14:49:09.602] DEBUG: <PixTestHighRate.cc/doCalDelScan:L435> roc 15: caldel = 167 eff = 0.999952
[14:49:09.606] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:49:09.629] INFO: enter test to run
[14:49:48.623] INFO: test: HighRate no parameter change
[14:49:48.623] INFO: running: highrate
[14:49:48.623] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:49:48.623] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:49:48.623] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:49:48.624] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:49:48.624] INFO: ----------------------------------------------------------------------
[14:49:48.624] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:49:48.624] INFO: ----------------------------------------------------------------------
[14:49:48.625] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:49:48.625] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:49:48.625] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:49:48.625] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:49:48.625] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:49:48.625] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:49:48.625] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:49:48.625] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:49:48.625] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:49:48.625] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:49:48.625] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:49:48.625] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:49:48.632] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:49:49.238] INFO: Expecting 208000 events.
[14:50:01.014] INFO: 208000 events read in total (11249ms).
[14:50:01.017] INFO: Test took 12385ms.
[14:50:01.152] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:01.152] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 1214303
[14:50:01.152] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:50:01.152] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:01.400] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 9 1 2 0 0 0 0 0 2 0
[14:50:01.400] INFO: number of red-efficiency pixels: 71 45 68 110 118 130 149 87 88 94 108 88 96 56 21 28
[14:50:01.400] INFO: number of X-ray hits detected: 63068 42275 62081 96351 102336 107023 103971 78592 74742 86538 88342 73055 77628 48740 20281 22735
[14:50:01.400] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:50:01.400] INFO: number of Vcal hits detected: 207927 207905 207932 207888 207878 207869 207357 207791 207814 207898 207888 207912 207903 207944 207881 207972
[14:50:01.400] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[14:50:01.400] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.7 99.9 99.9 100.0 99.9 100.0 100.0 100.0 99.9 100.0
[14:50:01.400] INFO: X-ray hit rate [MHz/cm2]: 18.5 12.4 18.2 28.2 30.0 31.4 30.5 23.0 21.9 25.4 25.9 21.4 22.8 14.3 5.9 6.7
[14:50:01.400] INFO: PixTestHighRate::doXPixelAlive() done
[14:50:01.444] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:50:01.444] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:50:01.444] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:50:01.444] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:50:01.444] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:50:01.444] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:50:01.444] INFO: PixTest:: pg_setup set to default.
[14:50:01.444] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:50:01.452] INFO: enter test to run
[14:50:25.327] INFO: test: HighRate no parameter change
[14:50:25.327] INFO: running: highrate
[14:50:25.327] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:50:25.327] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:50:25.327] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:50:25.328] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:50:25.328] INFO: ----------------------------------------------------------------------
[14:50:25.328] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:50:25.328] INFO: ----------------------------------------------------------------------
[14:50:25.329] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:50:25.329] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:50:25.329] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:50:25.329] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:50:25.329] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:50:25.329] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:50:25.329] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:50:25.329] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:50:25.329] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:50:25.329] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:50:25.329] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:50:25.329] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:50:25.336] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:25.939] INFO: Expecting 208000 events.
[14:50:39.348] INFO: 208000 events read in total (12882ms).
[14:50:39.353] INFO: Test took 14017ms.
[14:50:39.623] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:50:39.623] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 2476387
[14:50:39.623] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:50:39.624] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:50:39.916] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 9 1 2 0 0 0 0 0 2 0
[14:50:39.916] INFO: number of red-efficiency pixels: 236 137 224 317 345 448 466 244 210 272 325 228 287 178 43 55
[14:50:39.916] INFO: number of X-ray hits detected: 132336 89017 130397 203233 215094 223472 218464 161323 156617 182332 186548 153348 163030 103297 42728 48606
[14:50:39.916] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:50:39.916] INFO: number of Vcal hits detected: 207747 207804 207758 207664 207632 207506 207013 207635 207683 207708 207652 207762 207688 207817 207859 207944
[14:50:39.916] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[14:50:39.916] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.5 99.8 99.8 99.9 99.8 99.9 99.9 99.9 99.9 100.0
[14:50:39.916] INFO: X-ray hit rate [MHz/cm2]: 38.8 26.1 38.2 59.6 63.0 65.5 64.0 47.3 45.9 53.4 54.7 44.9 47.8 30.3 12.5 14.2
[14:50:39.916] INFO: PixTestHighRate::doXPixelAlive() done
[14:50:39.964] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:50:39.964] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:50:39.964] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:50:39.964] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:50:39.964] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:50:39.964] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:50:39.964] INFO: PixTest:: pg_setup set to default.
[14:50:39.964] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:50:39.976] INFO: enter test to run
[14:50:57.151] INFO: test: HighRate no parameter change
[14:50:57.151] INFO: running: highrate
[14:50:57.151] DEBUG: <PixTestHighRate.cc/setParameter:L68> setting fParTriggerFrequency -> 20
[14:50:57.151] DEBUG: <PixTestHighRate.cc/init:L211> PixTestHighRate::init()
[14:50:57.151] DEBUG: <PixTestHighRate.cc/PixTestHighRate:L31> PixTestHighRate ctor(PixSetup &a, string, TGTab *)
[14:50:57.152] DEBUG: <PixTestHighRate.cc/runCommand:L165> running command: xpixelalive
[14:50:57.152] INFO: ----------------------------------------------------------------------
[14:50:57.152] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:50:57.152] INFO: ----------------------------------------------------------------------
[14:50:57.152] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: clk: 4
[14:50:57.152] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: ctr: 4
[14:50:57.152] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: sda: 19
[14:50:57.152] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: tin: 9
[14:50:57.152] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: level: 15
[14:50:57.152] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L464> old set: triggerdelay: 0
[14:50:57.152] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: clk: 4
[14:50:57.152] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: ctr: 4
[14:50:57.152] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: sda: 19
[14:50:57.152] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: tin: 9
[14:50:57.152] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: level: 15
[14:50:57.152] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L480> setting: triggerdelay: 20
[14:50:57.159] DEBUG: <PixTest.cc/efficiencyMaps:L396> attempt #0
[14:50:57.762] INFO: Expecting 208000 events.
[14:51:12.933] INFO: 208000 events read in total (14644ms).
[14:51:12.940] INFO: Test took 15781ms.
[14:51:13.356] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:51:13.356] DEBUG: <PixTest.cc/efficiencyMaps:L407> eff result size = 3716475
[14:51:13.356] DEBUG: <PixTest.cc/efficiencyMaps:L412> Create hists highRate_C0 .. highRate_C15
[14:51:13.356] DEBUG: <PixTest.cc/efficiencyMaps:L426> booking xray maps for unmasked detector
[14:51:13.692] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 8 1 2 0 0 0 0 0 2 0
[14:51:13.692] INFO: number of red-efficiency pixels: 441 260 558 710 794 1194 1079 549 463 547 849 431 663 406 84 80
[14:51:13.692] INFO: number of X-ray hits detected: 199337 134517 197269 307708 325869 337881 331728 242024 239033 277434 282662 232565 246665 156808 64683 73746
[14:51:13.692] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:51:13.692] INFO: number of Vcal hits detected: 207492 207655 207304 207192 207050 206439 206177 207253 207403 207367 207005 207511 207225 207526 207815 207918
[14:51:13.692] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.6 99.6 99.3 99.4 99.7 99.8 99.7 99.6 99.8 99.7 99.8 100.0 100.0
[14:51:13.692] INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.7 99.6 99.5 99.2 99.1 99.6 99.7 99.7 99.5 99.8 99.6 99.8 99.9 100.0
[14:51:13.692] INFO: X-ray hit rate [MHz/cm2]: 58.4 39.4 57.8 90.2 95.5 99.0 97.2 70.9 70.1 81.3 82.9 68.2 72.3 46.0 19.0 21.6
[14:51:13.693] INFO: PixTestHighRate::doXPixelAlive() done
[14:51:13.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: clk: 4
[14:51:13.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: ctr: 4
[14:51:13.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: sda: 19
[14:51:13.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: tin: 9
[14:51:13.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: level: 15
[14:51:13.744] DEBUG: <PixTestHighRate.cc/doXPixelAlive:L599> resetting: triggerdelay: 0
[14:51:13.744] INFO: PixTest:: pg_setup set to default.
[14:51:13.744] DEBUG: <PixTestHighRate.cc/~PixTestHighRate:L258> PixTestHighRate dtor
[14:51:13.757] INFO: enter test to run
[14:51:17.110] INFO: test: exit no parameter change
[14:51:17.111] DEBUG: <pXar.cc/main:L340> Final Analog Current: 392.3mA
[14:51:17.112] DEBUG: <pXar.cc/main:L341> Final Digital Current: 467.9mA
[14:51:17.112] DEBUG: <pXar.cc/main:L342> Final Module Temperature: 18.3 C
[14:51:17.112] DEBUG: <PixMonitor.cc/dumpSummaries:L39> PixMonitor::dumpSummaries
[14:51:17.444] QUIET: Connection to board 33 closed.
[14:51:17.457] INFO: pXar: this is the end, my friend
[14:51:17.458] DEBUG: <PixSetup.cc/~PixSetup:L68> PixSetup free fPxarMemory