| #!/bin/bash |
| # SPDX-License-Identifier: GPL-2.0+ |
| # |
| # Analyze a given results directory for rcuscale performance measurements, |
| # looking for ftrace data. Exits with 0 if data was found, analyzed, and |
| # printed. Intended to be invoked from kvm-recheck-rcuscale.sh after |
| # argument checking. |
| # |
| # Usage: kvm-recheck-rcuscale-ftrace.sh resdir |
| # |
| # Copyright (C) IBM Corporation, 2016 |
| # |
| # Authors: Paul E. McKenney <paulmck@linux.ibm.com> |
| |
| i="$1" |
| . functions.sh |
| |
| if test "`grep -c 'rcu_exp_grace_period.*start' < $i/console.log`" -lt 100 |
| then |
| exit 10 |
| fi |
| |
| sed -e 's/^\[[^]]*]//' < $i/console.log | |
| grep 'us : rcu_exp_grace_period' | |
| sed -e 's/us : / : /' | |
| tr -d '\015' | |
| awk ' |
| $8 == "start" { |
| if (startseq != "") |
| nlost++; |
| starttask = $1; |
| starttime = $3; |
| startseq = $7; |
| seqtask[startseq] = starttask; |
| } |
| |
| $8 == "end" { |
| if (startseq == $7) { |
| curgpdur = $3 - starttime; |
| gptimes[++n] = curgpdur; |
| gptaskcnt[starttask]++; |
| sum += curgpdur; |
| if (curgpdur > 1000) |
| print "Long GP " starttime "us to " $3 "us (" curgpdur "us)"; |
| startseq = ""; |
| } else { |
| # Lost a message or some such, reset. |
| startseq = ""; |
| nlost++; |
| } |
| } |
| |
| $8 == "done" && seqtask[$7] != $1 { |
| piggybackcnt[$1]++; |
| } |
| |
| END { |
| newNR = asort(gptimes); |
| if (newNR <= 0) { |
| print "No ftrace records found???" |
| exit 10; |
| } |
| pct50 = int(newNR * 50 / 100); |
| if (pct50 < 1) |
| pct50 = 1; |
| pct90 = int(newNR * 90 / 100); |
| if (pct90 < 1) |
| pct90 = 1; |
| pct99 = int(newNR * 99 / 100); |
| if (pct99 < 1) |
| pct99 = 1; |
| div = 10 ** int(log(gptimes[pct90]) / log(10) + .5) / 100; |
| print "Histogram bucket size: " div; |
| last = gptimes[1] - 10; |
| count = 0; |
| for (i = 1; i <= newNR; i++) { |
| current = div * int(gptimes[i] / div); |
| if (last == current) { |
| count++; |
| } else { |
| if (count > 0) |
| print last, count; |
| count = 1; |
| last = current; |
| } |
| } |
| if (count > 0) |
| print last, count; |
| print "Distribution of grace periods across tasks:"; |
| for (i in gptaskcnt) { |
| print "\t" i, gptaskcnt[i]; |
| nbatches += gptaskcnt[i]; |
| } |
| ngps = nbatches; |
| print "Distribution of piggybacking across tasks:"; |
| for (i in piggybackcnt) { |
| print "\t" i, piggybackcnt[i]; |
| ngps += piggybackcnt[i]; |
| } |
| print "Average grace-period duration: " sum / newNR " microseconds"; |
| print "Minimum grace-period duration: " gptimes[1]; |
| print "50th percentile grace-period duration: " gptimes[pct50]; |
| print "90th percentile grace-period duration: " gptimes[pct90]; |
| print "99th percentile grace-period duration: " gptimes[pct99]; |
| print "Maximum grace-period duration: " gptimes[newNR]; |
| print "Grace periods: " ngps + 0 " Batches: " nbatches + 0 " Ratio: " ngps / nbatches " Lost: " nlost + 0; |
| print "Computed from ftrace data."; |
| }' |
| exit 0 |