Re: [PATCH V4 0/5] New metricgroup output in perf stat default mode

From: Liang, Kan
Date: Fri Jun 16 2023 - 09:26:35 EST




On 2023-06-16 1:59 a.m., Ian Rogers wrote:
> On Thu, Jun 15, 2023 at 8:14 PM <kan.liang@xxxxxxxxxxxxxxx> wrote:
>>
>> From: Kan Liang <kan.liang@xxxxxxxxxxxxxxx>
>>
>> Changes since V3:
>> - Move the full name (PMU + metricgroup name) generation from the metric
>> code to the output code. (Ian)
>> - Add default tags for Hisi hip08 L1 metrics (John)
>> - Some patches have been merged. Drop them from the V4.
>>
>> Changes since V2:
>> - Fixes memory leak (Ian)
>> (Ian, I cannot reproduce the memory leak on all my machines. Please
>> check whether the fix works on your side. Thanks.)
>> - Add Reviewed-by tags for several patches.
>>
>> Changes since V1:
>> - Remove EVSEL_EVENT_MASK and use the __evsel__match which is suggested
>> by Ian.
>> - Support TopdownL1 on both e-core and p-core of ADL in the default
>> mode. (Ian)
>> - Have separate patches for the modifications of metricgroup and output.
>> (Ian)
>> - Does 2nd sort for the Default metricgroup. Remove the logic of
>> changing the associated metric event. (Ian)
>> - Move all the metric related code to stat-shadow (Ian)
>> - Move the commong functions between stat+csv_output and stat+std_output
>> to the lib directory (Ian)
>>
>> In the default mode, the current output of the metricgroup include both
>> events and metrics, which is not necessary and makes the output hard to
>> read. Also, different ARCHs (even different generations of the ARCH) may
>> have a different output format because of the different events in a
>> metrics.
>>
>> The patch proposes a new output format which only outputting the value
>> of each metric and the metricgroup name. It can brings a clean and
>> consistent output format among ARCHs and generations.
>>
>> The patches 1-2 introduce the new metricgroup output.
>>
>> The patches 3-4 improve the tests to cover the default mode.
>>
>> The patch 5 update the event list for Hisi hip08.
>>
>> Here are some examples for the new output.
>>
>> STD output:
>>
>> On SPR
>>
>> perf stat -a sleep 1
>>
>> Performance counter stats for 'system wide':
>>
>> 226,054.13 msec cpu-clock # 224.588 CPUs utilized
>> 932 context-switches # 4.123 /sec
>> 224 cpu-migrations # 0.991 /sec
>> 76 page-faults # 0.336 /sec
>> 45,940,682 cycles # 0.000 GHz
>> 36,676,047 instructions # 0.80 insn per cycle
>> 7,044,516 branches # 31.163 K/sec
>> 62,169 branch-misses # 0.88% of all branches
>> TopdownL1 # 68.7 % tma_backend_bound
>> # 3.1 % tma_bad_speculation
>> # 13.0 % tma_frontend_bound
>> # 15.2 % tma_retiring
>> TopdownL2 # 2.7 % tma_branch_mispredicts
>> # 19.6 % tma_core_bound
>> # 4.8 % tma_fetch_bandwidth
>> # 8.3 % tma_fetch_latency
>> # 2.9 % tma_heavy_operations
>> # 12.3 % tma_light_operations
>> # 0.4 % tma_machine_clears
>> # 49.1 % tma_memory_bound
>>
>> 1.006529767 seconds time elapsed
>>
>> perf stat -a sleep 1
>>
>> Performance counter stats for 'system wide':
>>
>> 32,127.99 msec cpu-clock # 31.992 CPUs utilized
>> 240 context-switches # 7.470 /sec
>> 32 cpu-migrations # 0.996 /sec
>> 74 page-faults # 2.303 /sec
>> 6,313,960 cpu_core/cycles/ # 0.000 GHz
>> 257,711,907 cpu_atom/cycles/ # 0.008 GHz (54.18%)
>> 4,477,162 cpu_core/instructions/ # 0.71 insn per cycle
>> 37,721,481 cpu_atom/instructions/ # 5.97 insn per cycle (63.33%)
>> 809,747 cpu_core/branches/ # 25.204 K/sec
>> 6,621,226 cpu_atom/branches/ # 206.089 K/sec (63.32%)
>> 39,667 cpu_core/branch-misses/ # 4.90% of all branches
>> 1,032,146 cpu_atom/branch-misses/ # 127.47% of all branches (63.33%)
>> TopdownL1 (cpu_core) # nan % tma_backend_bound
>> # 0.0 % tma_bad_speculation
>> # nan % tma_frontend_bound
>> # nan % tma_retiring
>> TopdownL1 (cpu_atom) # 13.6 % tma_bad_speculation (63.36%)
>> # 41.1 % tma_frontend_bound (63.54%)
>> # 39.2 % tma_backend_bound
>> # 39.2 % tma_backend_bound_aux (63.93%)
>> # 5.4 % tma_retiring (64.15%)
>>
>> 1.004244114 seconds time elapsed
>>
>> JSON output
>>
>> on SPR
>>
>> perf stat --json -a sleep 1
>> {"counter-value" : "225904.823297", "unit" : "msec", "event" : "cpu-clock", "event-runtime" : 225904323425, "pcnt-running" : 100.00, "metric-value" : "224.456872", "metric-unit" : "CPUs utilized"}
>> {"counter-value" : "986.000000", "unit" : "", "event" : "context-switches", "event-runtime" : 225904108985, "pcnt-running" : 100.00, "metric-value" : "4.364670", "metric-unit" : "/sec"}
>> {"counter-value" : "224.000000", "unit" : "", "event" : "cpu-migrations", "event-runtime" : 225904016141, "pcnt-running" : 100.00, "metric-value" : "0.991568", "metric-unit" : "/sec"}
>> {"counter-value" : "76.000000", "unit" : "", "event" : "page-faults", "event-runtime" : 225903913270, "pcnt-running" : 100.00, "metric-value" : "0.336425", "metric-unit" : "/sec"}
>> {"counter-value" : "48433482.000000", "unit" : "", "event" : "cycles", "event-runtime" : 225903792732, "pcnt-running" : 100.00, "metric-value" : "0.000214", "metric-unit" : "GHz"}
>> {"counter-value" : "38620409.000000", "unit" : "", "event" : "instructions", "event-runtime" : 225903657830, "pcnt-running" : 100.00, "metric-value" : "0.797391", "metric-unit" : "insn per cycle"}
>> {"counter-value" : "7369473.000000", "unit" : "", "event" : "branches", "event-runtime" : 225903464328, "pcnt-running" : 100.00, "metric-value" : "32.622026", "metric-unit" : "K/sec"}
>> {"counter-value" : "54747.000000", "unit" : "", "event" : "branch-misses", "event-runtime" : 225903234523, "pcnt-running" : 100.00, "metric-value" : "0.742889", "metric-unit" : "of all branches"}
>> {"event-runtime" : 225902840555, "pcnt-running" : 100.00, "metricgroup" : "TopdownL1"}
>> {"metric-value" : "69.950631", "metric-unit" : "% tma_backend_bound"}
>> {"metric-value" : "2.771783", "metric-unit" : "% tma_bad_speculation"}
>> {"metric-value" : "12.026074", "metric-unit" : "% tma_frontend_bound"}
>> {"metric-value" : "15.251513", "metric-unit" : "% tma_retiring"}
>> {"event-runtime" : 225902840555, "pcnt-running" : 100.00, "metricgroup" : "TopdownL2"}
>> {"metric-value" : "2.351757", "metric-unit" : "% tma_branch_mispredicts"}
>> {"metric-value" : "19.729771", "metric-unit" : "% tma_core_bound"}
>> {"metric-value" : "4.555207", "metric-unit" : "% tma_fetch_bandwidth"}
>> {"metric-value" : "7.470867", "metric-unit" : "% tma_fetch_latency"}
>> {"metric-value" : "2.938808", "metric-unit" : "% tma_heavy_operations"}
>> {"metric-value" : "12.312705", "metric-unit" : "% tma_light_operations"}
>> {"metric-value" : "0.420026", "metric-unit" : "% tma_machine_clears"}
>> {"metric-value" : "50.220860", "metric-unit" : "% tma_memory_bound"}
>>
>> On hybrid
>>
>> perf stat --json -a sleep 1
>> {"counter-value" : "32131.530625", "unit" : "msec", "event" : "cpu-clock", "event-runtime" : 32131536951, "pcnt-running" : 100.00, "metric-value" : "31.992642", "metric-unit" : "CPUs utilized"}
>> {"counter-value" : "328.000000", "unit" : "", "event" : "context-switches", "event-runtime" : 32131525778, "pcnt-running" : 100.00, "metric-value" : "10.208042", "metric-unit" : "/sec"}
>> {"counter-value" : "32.000000", "unit" : "", "event" : "cpu-migrations", "event-runtime" : 32131515104, "pcnt-running" : 100.00, "metric-value" : "0.995906", "metric-unit" : "/sec"}
>> {"counter-value" : "353.000000", "unit" : "", "event" : "page-faults", "event-runtime" : 32131501396, "pcnt-running" : 100.00, "metric-value" : "10.986094", "metric-unit" : "/sec"}
>> {"counter-value" : "18685492.000000", "unit" : "", "event" : "cpu_core/cycles/", "event-runtime" : 16061585292, "pcnt-running" : 100.00, "metric-value" : "0.000582", "metric-unit" : "GHz"}
>> {"counter-value" : "255620352.000000", "unit" : "", "event" : "cpu_atom/cycles/", "event-runtime" : 8690268422, "pcnt-running" : 54.00, "metric-value" : "0.007955", "metric-unit" : "GHz"}
>> {"counter-value" : "15489913.000000", "unit" : "", "event" : "cpu_core/instructions/", "event-runtime" : 16061582200, "pcnt-running" : 100.00, "metric-value" : "0.828981", "metric-unit" : "insn per cycle"}
>> {"counter-value" : "38790161.000000", "unit" : "", "event" : "cpu_atom/instructions/", "event-runtime" : 10163133324, "pcnt-running" : 63.00, "metric-value" : "2.075951", "metric-unit" : "insn per cycle"}
>> {"counter-value" : "2908031.000000", "unit" : "", "event" : "cpu_core/branches/", "event-runtime" : 16061563416, "pcnt-running" : 100.00, "metric-value" : "90.503967", "metric-unit" : "K/sec"}
>> {"counter-value" : "6814948.000000", "unit" : "", "event" : "cpu_atom/branches/", "event-runtime" : 10161711336, "pcnt-running" : 63.00, "metric-value" : "212.095343", "metric-unit" : "K/sec"}
>> {"counter-value" : "97638.000000", "unit" : "", "event" : "cpu_core/branch-misses/", "event-runtime" : 16061535261, "pcnt-running" : 100.00, "metric-value" : "3.357530", "metric-unit" : "of all branches"}
>> {"counter-value" : "1017066.000000", "unit" : "", "event" : "cpu_atom/branch-misses/", "event-runtime" : 10159971797, "pcnt-running" : 63.00, "metric-value" : "34.974386", "metric-unit" : "of all branches"}
>> {"event-runtime" : 16061513607, "pcnt-running" : 100.00, "metricgroup" : "TopdownL1 (cpu_core)"}
>> {"metric-value" : "nan", "metric-unit" : "% tma_backend_bound"}
>> {"metric-value" : "0.000000", "metric-unit" : "% tma_bad_speculation"}
>> {"metric-value" : "nan", "metric-unit" : "% tma_frontend_bound"}
>> {"metric-value" : "nan", "metric-unit" : "% tma_retiring"}
>> {"event-runtime" : 10157398501, "pcnt-running" : 63.00, "metricgroup" : "TopdownL1 (cpu_atom)"}
>> {"metric-value" : "13.719821", "metric-unit" : "% tma_bad_speculation"}
>> {"event-runtime" : 10178698656, "pcnt-running" : 63.00, "metric-value" : "41.016738", "metric-unit" : "% tma_frontend_bound"}
>> {"event-runtime" : 10240582902, "pcnt-running" : 63.00, "metric-value" : "39.327764", "metric-unit" : "% tma_backend_bound"}
>> {"metric-value" : "39.327764", "metric-unit" : "% tma_backend_bound_aux"}
>> {"event-runtime" : 10284284920, "pcnt-running" : 64.00, "metric-value" : "5.374638", "metric-unit" : "% tma_retiring"}
>>
>> CSV output
>>
>> On SPR
>>
>> perf stat -x, -a sleep 1
>> 225851.20,msec,cpu-clock,225850700108,100.00,224.431,CPUs utilized
>> 976,,context-switches,225850504803,100.00,4.321,/sec
>> 224,,cpu-migrations,225850410336,100.00,0.992,/sec
>> 76,,page-faults,225850304155,100.00,0.337,/sec
>> 52288305,,cycles,225850188531,100.00,0.000,GHz
>> 37977214,,instructions,225850071251,100.00,0.73,insn per cycle
>> 7299859,,branches,225849890722,100.00,32.322,K/sec
>> 51102,,branch-misses,225849672536,100.00,0.70,of all branches
>> ,225849327050,100.00,,,,TopdownL1
>> ,,,,,70.1,% tma_backend_bound
>> ,,,,,2.7,% tma_bad_speculation
>> ,,,,,12.5,% tma_frontend_bound
>> ,,,,,14.6,% tma_retiring
>> ,225849327050,100.00,,,,TopdownL2
>> ,,,,,2.3,% tma_branch_mispredicts
>> ,,,,,19.6,% tma_core_bound
>> ,,,,,4.6,% tma_fetch_bandwidth
>> ,,,,,7.9,% tma_fetch_latency
>> ,,,,,2.9,% tma_heavy_operations
>> ,,,,,11.7,% tma_light_operations
>> ,,,,,0.5,% tma_machine_clears
>> ,,,,,50.5,% tma_memory_bound
>>
>> On Hybrid
>>
>> perf stat -x, -a sleep 1
>> 32139.34,msec,cpu-clock,32139351409,100.00,32.001,CPUs utilized
>> 225,,context-switches,32139342672,100.00,7.001,/sec
>> 32,,cpu-migrations,32139337772,100.00,0.996,/sec
>> 72,,page-faults,32139328384,100.00,2.240,/sec
>> 6766433,,cpu_core/cycles/,16067551558,100.00,0.000,GHz
>> 256500230,,cpu_atom/cycles/,8695757391,54.00,0.008,GHz
>> 4688595,,cpu_core/instructions/,16067558976,100.00,0.69,insn per cycle
>> 37487490,,cpu_atom/instructions/,10165193856,63.00,5.54,insn per cycle
>> 845211,,cpu_core/branches/,16067540225,100.00,26.298,K/sec
>> 6571193,,cpu_atom/branches/,10155940853,63.00,204.459,K/sec
>> 41359,,cpu_core/branch-misses/,16067516493,100.00,4.89,of all branches
>> 1020231,,cpu_atom/branch-misses/,10159363620,63.00,120.71,of all branches
>> ,16067494476,100.00,,,,TopdownL1 (cpu_core)
>> ,,,,,,% tma_backend_bound
>> ,,,,,0.0,% tma_bad_speculation
>> ,,,,,,% tma_frontend_bound
>> ,,,,,,% tma_retiring
>> ,10160989992,63.00,,,,TopdownL1 (cpu_atom)
>> ,,,,,13.8,% tma_bad_speculation
>> ,10188319019,63.00,,,41.3,% tma_frontend_bound
>> ,10258326591,63.00,,,38.6,% tma_backend_bound
>> ,,,,,38.6,% tma_backend_bound_aux
>> ,10282689488,64.00,,,5.4,% tma_retiring
>>
>> Kan Liang (5):
>> perf metrics: Sort the Default metricgroup
>> perf stat: New metricgroup output for the default mode
>> perf test: Move all the check functions of stat csv output to lib
>> perf test: Add test case for the standard perf stat output
>> perf vendor events arm64: Add default tags for Hisi hip08 L1 metrics
>
> Just to be clear, I'm happy with this to be submitted having put
> reviewed/acked-by on it.
>

Thanks Ian. Appreciate all your feedback and comments.

Thanks,
Kan

> Thanks,
> Ian
>
>> tools/perf/builtin-stat.c | 1 +
>> .../arch/arm64/hisilicon/hip08/metrics.json | 12 +-
>> tools/perf/tests/shell/lib/stat_output.sh | 169 ++++++++++++++++
>> tools/perf/tests/shell/stat+csv_output.sh | 188 ++----------------
>> tools/perf/tests/shell/stat+std_output.sh | 108 ++++++++++
>> tools/perf/util/evsel.h | 1 +
>> tools/perf/util/metricgroup.c | 26 +++
>> tools/perf/util/metricgroup.h | 3 +
>> tools/perf/util/stat-display.c | 108 +++++++++-
>> tools/perf/util/stat-shadow.c | 131 ++++++++++--
>> tools/perf/util/stat.h | 15 ++
>> 11 files changed, 563 insertions(+), 199 deletions(-)
>> create mode 100755 tools/perf/tests/shell/lib/stat_output.sh
>> create mode 100755 tools/perf/tests/shell/stat+std_output.sh
>>
>> --
>> 2.35.1
>>