Intel Profiler Example

Simple Gaudi algorithm to test Intel Profiler.

A video guide to installing profiler package.

CpuHungryAlg algorithms define 4 functions:

double mysin();
double mycos();
double mytan();
double myatan();

, and we would like to profile them. Execution of this functions depends on name of algorithm.

StatusCode CpuHungryAlg::execute() {
  m_nevent++;
  double result = 0;
  if (name() == "Alg1") {
    result = mysin();
  }else if (name() == "Alg2") {
    result = mycos();
  }else {
    result = mytan();
  }
  return StatusCode::SUCCESS;
}

Run

$> intelprofiler -o ~/profiler GaudiTestSuite/options/IntelProfiler.py

At the end you should see something like that:

...
IntelProfilerAu...   INFO Start profiling (event #1)
IntelProfilerAu...  DEBUG   Skip component TopSequence
IntelProfilerAu...  DEBUG     Start profiling component TopSequence SubSequence
IntelProfilerAu...  DEBUG     Start event type 2 for TopSequence SubSequence
IntelProfilerAu...  DEBUG     Pause event 2
IntelProfilerAu...  DEBUG       Start profiling component TopSequence SubSequence Alg1
IntelProfilerAu...  DEBUG       Start event type 3 for TopSequence SubSequence Alg1
IntelProfilerAu...  DEBUG       End event for Alg1
IntelProfilerAu...  DEBUG     Resume event for 2
IntelProfilerAu...  DEBUG       Skip component Alg2
IntelProfilerAu...  DEBUG     Resume
IntelProfilerAu...  DEBUG     Pause event 2
IntelProfilerAu...  DEBUG       Start profiling component TopSequence SubSequence Alg3
IntelProfilerAu...  DEBUG       Start event type 4 for TopSequence SubSequence Alg3
IntelProfilerAu...  DEBUG       End event for Alg3
IntelProfilerAu...  DEBUG     Resume event for 2
IntelProfilerAu...  DEBUG     End event for SubSequence
IntelProfilerAu...  DEBUG   Pause
IntelProfilerAu...  DEBUG     Skip component Alg4
IntelProfilerAu...  DEBUG  Pause
IntelProfilerAu...   INFO Stop profiling (event #2)
ApplicationMgr       INFO Application Manager Stopped successfully
EventLoopMgr         INFO Histograms converted successfully according to request.
ApplicationMgr       INFO Application Manager Finalized successfully
ApplicationMgr       INFO Application Manager Terminated successfully
Using result path `/data/amazurov/Amplifier/IntelProfilingExample/r050hs`

, where Using result path `/data/amazurov/Amplifier/IntelProfilingExample/r050hs` is our profiling database.

Analyze

GUI

$> amplxe-gui ~/profiler/r000hs

Command line

Hot spots report:

amplxe-cl -report hotspots -r ~/profiler/r000hs

If options file looks like this:

#!/usr/bin/env gaudirun.py
 
from Gaudi.Configuration import *
from Configurables import IntelProfilerAuditor, CpuHungryAlg
 
MessageSvc().OutputLevel = INFO
 
alg1 = CpuHungryAlg("Alg1")
alg2 = CpuHungryAlg("Alg2")
alg3 = CpuHungryAlg("Alg3")
alg4 = CpuHungryAlg("Alg4")
 
alg1.Loops = alg2.Loops = alg3.Loops = alg4.Loops = 5000000
 
subtop = Sequencer('SubSequence', Members = [alg1, alg2, alg3], StopOverride = True )
top = Sequencer('TopSequence', Members = [subtop, alg4], StopOverride = True )
 
profiler = IntelProfilerAuditor()
profiler.OutputLevel = DEBUG
profiler.StartFromEventN = 1
profiler.StopAtEventN = 2
profiler.ComponentsForTaskTypes = []
profiler.IncludeAlgorithms = ["SubSequence"]
profiler.ExcludeAlgorithms = ["Alg2"]
AuditorSvc().Auditors +=  [profiler]
 
ApplicationMgr( EvtMax = 3,
                EvtSel = 'NONE',
                HistogramPersistency = 'NONE',
                TopAlg = [top],
                AuditAlgorithms=True)

, as a result we can see the following:

Function        Module  CPU Time
CpuHungryAlg::mysin     libIntelProfilerExample.so      0.410
CpuHungryAlg::myatan    libIntelProfilerExample.so      0.380
CpuHungryAlg::mytan     libIntelProfilerExample.so      0.370
CpuHungryAlg::mycos     libIntelProfilerExample.so      0.250
[Import thunk tan]      libIntelProfilerExample.so      0.010

Report by algorithm chain:

$> amplxe-cl -report hotspots -r ~/profiler/r000hs --group-by task

Result:

Task Type       CPU Time
TopSequence SubSequence Alg3    0.759
TopSequence SubSequence Alg1    0.410
TopSequence SubSequence 0.250

Report by algorithm chain with function's name:

$> amplxe-cl -report hotspots -r ~/profiler/r000hs --group-by task-function

Result:

Function        Task Type       CPU Time
CpuHungryAlg::mysin     TopSequence SubSequence Alg1    0.410
CpuHungryAlg::myatan    TopSequence SubSequence Alg3    0.380
CpuHungryAlg::mytan     TopSequence SubSequence Alg3    0.370
CpuHungryAlg::mycos     TopSequence SubSequence 0.250
[Import thunk tan]      TopSequence SubSequence Alg3    0.010