kvm-recheck-rcuscale-ftrace.sh

  1#!/bin/bash
  2# SPDX-License-Identifier: GPL-2.0+
  3#
  4# Analyze a given results directory for rcuscale performance measurements,
  5# looking for ftrace data.  Exits with 0 if data was found, analyzed, and
  6# printed.  Intended to be invoked from kvm-recheck-rcuscale.sh after
  7# argument checking.
  8#
  9# Usage: kvm-recheck-rcuscale-ftrace.sh resdir
 10#
 11# Copyright (C) IBM Corporation, 2016
 12#
 13# Authors: Paul E. McKenney <paulmck@linux.ibm.com>
 14
 15i="$1"
 16. functions.sh
 17
 18if test "`grep -c 'rcu_exp_grace_period.*start' < $i/console.log`" -lt 100
 19then
 20	exit 10
 21fi
 22
 23sed -e 's/^\[[^]]*]//' < $i/console.log |
 24grep 'us : rcu_exp_grace_period' |
 25sed -e 's/us : / : /' |
 26tr -d '\015' |
 27awk '
 28$8 == "start" {
 29	if (startseq != "")
 30		nlost++;
 31	starttask = $1;
 32	starttime = $3;
 33	startseq = $7;
 34	seqtask[startseq] = starttask;
 35}
 36
 37$8 == "end" {
 38	if (startseq == $7) {
 39		curgpdur = $3 - starttime;
 40		gptimes[++n] = curgpdur;
 41		gptaskcnt[starttask]++;
 42		sum += curgpdur;
 43		if (curgpdur > 1000)
 44			print "Long GP " starttime "us to " $3 "us (" curgpdur "us)";
 45		startseq = "";
 46	} else {
 47		# Lost a message or some such, reset.
 48		startseq = "";
 49		nlost++;
 50	}
 51}
 52
 53$8 == "done" && seqtask[$7] != $1 {
 54	piggybackcnt[$1]++;
 55}
 56
 57END {
 58	newNR = asort(gptimes);
 59	if (newNR <= 0) {
 60		print "No ftrace records found???"
 61		exit 10;
 62	}
 63	pct50 = int(newNR * 50 / 100);
 64	if (pct50 < 1)
 65		pct50 = 1;
 66	pct90 = int(newNR * 90 / 100);
 67	if (pct90 < 1)
 68		pct90 = 1;
 69	pct99 = int(newNR * 99 / 100);
 70	if (pct99 < 1)
 71		pct99 = 1;
 72	div = 10 ** int(log(gptimes[pct90]) / log(10) + .5) / 100;
 73	print "Histogram bucket size: " div;
 74	last = gptimes[1] - 10;
 75	count = 0;
 76	for (i = 1; i <= newNR; i++) {
 77		current = div * int(gptimes[i] / div);
 78		if (last == current) {
 79			count++;
 80		} else {
 81			if (count > 0)
 82				print last, count;
 83			count = 1;
 84			last = current;
 85		}
 86	}
 87	if (count > 0)
 88		print last, count;
 89	print "Distribution of grace periods across tasks:";
 90	for (i in gptaskcnt) {
 91		print "\t" i, gptaskcnt[i];
 92		nbatches += gptaskcnt[i];
 93	}
 94	ngps = nbatches;
 95	print "Distribution of piggybacking across tasks:";
 96	for (i in piggybackcnt) {
 97		print "\t" i, piggybackcnt[i];
 98		ngps += piggybackcnt[i];
 99	}
100	print "Average grace-period duration: " sum / newNR " microseconds";
101	print "Minimum grace-period duration: " gptimes[1];
102	print "50th percentile grace-period duration: " gptimes[pct50];
103	print "90th percentile grace-period duration: " gptimes[pct90];
104	print "99th percentile grace-period duration: " gptimes[pct99];
105	print "Maximum grace-period duration: " gptimes[newNR];
106	print "Grace periods: " ngps + 0 " Batches: " nbatches + 0 " Ratio: " ngps / nbatches " Lost: " nlost + 0;
107	print "Computed from ftrace data.";
108}'
109exit 0