From patchwork Sat Sep 10 12:37:01 2011 Content-Type: text/plain; charset="utf-8" MIME-Version: 1.0 Content-Transfer-Encoding: 7bit X-Patchwork-Submitter: Andrew Murray X-Patchwork-Id: 114158 X-Patchwork-Delegate: wd@denx.de Return-Path: X-Original-To: incoming@patchwork.ozlabs.org Delivered-To: patchwork-incoming@bilbo.ozlabs.org Received: from theia.denx.de (theia.denx.de [85.214.87.163]) by ozlabs.org (Postfix) with ESMTP id 9FCFFB71BD for ; Sat, 10 Sep 2011 22:37:52 +1000 (EST) Received: from localhost (localhost [127.0.0.1]) by theia.denx.de (Postfix) with ESMTP id A1F862847E; Sat, 10 Sep 2011 14:37:42 +0200 (CEST) X-Virus-Scanned: Debian amavisd-new at theia.denx.de Received: from theia.denx.de ([127.0.0.1]) by localhost (theia.denx.de [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id Bw1jZ2iAUyxC; Sat, 10 Sep 2011 14:37:42 +0200 (CEST) Received: from theia.denx.de (localhost [127.0.0.1]) by theia.denx.de (Postfix) with ESMTP id 7567D2845F; Sat, 10 Sep 2011 14:37:34 +0200 (CEST) Received: from localhost (localhost [127.0.0.1]) by theia.denx.de (Postfix) with ESMTP id 027752844E for ; Sat, 10 Sep 2011 14:37:31 +0200 (CEST) X-Virus-Scanned: Debian amavisd-new at theia.denx.de Received: from theia.denx.de ([127.0.0.1]) by localhost (theia.denx.de [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id GuyyayKpLdrY for ; Sat, 10 Sep 2011 14:37:29 +0200 (CEST) X-policyd-weight: NOT_IN_SBL_XBL_SPAMHAUS=-1.5 NOT_IN_SPAMCOP=-1.5 NOT_IN_BL_NJABL=-1.5 (only DNSBL check requested) Received: from mail-wy0-f172.google.com (mail-wy0-f172.google.com [74.125.82.172]) by theia.denx.de (Postfix) with ESMTPS id DD7F228449 for ; Sat, 10 Sep 2011 14:37:27 +0200 (CEST) Received: by wyg24 with SMTP id 24so2031953wyg.3 for ; Sat, 10 Sep 2011 05:37:27 -0700 (PDT) Received: by 10.227.28.30 with SMTP id k30mr578631wbc.98.1315658246747; Sat, 10 Sep 2011 05:37:26 -0700 (PDT) Received: from localhost.localdomain (cpc1-bath4-0-0-cust211.aztw.cable.virginmedia.com [92.238.100.212]) by mx.google.com with ESMTPS id x19sm10270706wbn.25.2011.09.10.05.37.24 (version=TLSv1/SSLv3 cipher=OTHER); Sat, 10 Sep 2011 05:37:25 -0700 (PDT) From: Andrew Murray To: u-boot@lists.denx.de Date: Sat, 10 Sep 2011 13:37:01 +0100 Message-Id: <1315658227-4388-1-git-send-email-amurray@theiet.org> X-Mailer: git-send-email 1.7.4.1 In-Reply-To: <1314829261-13996-1-git-send-email-amurray@theiet.org> References: <1314829261-13996-1-git-send-email-amurray@theiet.org> Cc: Andrew Murray , arjan@linux.intel.com Subject: [U-Boot] [PATCH v2 1/7] Add bootgraph.pl script for generating a boot graph SVG file X-BeenThere: u-boot@lists.denx.de X-Mailman-Version: 2.1.9 Precedence: list List-Id: U-Boot discussion List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , MIME-Version: 1.0 Sender: u-boot-bounces@lists.denx.de Errors-To: u-boot-bounces@lists.denx.de From: Andrew Murray This is a port of the kernel's script/bootgraph.pl script which generates an SVG image illustrating boot time in UBoot. The script relies on additional output generated by UBoot during boot when the CONFIG_BOOT_TRACE option is enabled. Signed-off-by: Andrew Murray --- tools/bootgraph.pl | 189 ++++++++++++++++++++++++++++++++++++++++++++++++++++ 1 files changed, 189 insertions(+), 0 deletions(-) create mode 100755 tools/bootgraph.pl diff --git a/tools/bootgraph.pl b/tools/bootgraph.pl new file mode 100755 index 0000000..8b206b5 --- /dev/null +++ b/tools/bootgraph.pl @@ -0,0 +1,189 @@ +#!/usr/bin/perl + +# Copyright (C) 2011, Andrew Murray ) +# Copyright (C) 2008, Intel Corporation (Arjan van de Ven ) +# +# This program file is free software; you can redistribute it and/or modify it +# under the terms of the GNU General Public License as published by the +# Free Software Foundation; version 2 of the License. +# +# This program is distributed in the hope that it will be useful, but WITHOUT +# ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or +# FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License +# for more details. +# +# You should have received a copy of the GNU General Public License +# along with this program in a file named COPYING; if not, write to the +# Free Software Foundation, Inc., +# 51 Franklin Street, Fifth Floor, +# Boston, MA 02110-1301 USA +# +# This script was originally written by Arjan for use with the Linux kernel and +# subsequently ported across to UBoot by Andrew. +# +# This script turns a output trace from UBoot into a SVG graphic that shows +# which functions take how much time. You can view SVG graphics with various +# programs, including Inkscape, The Gimp and Firefox. +# +# For this script to work, the UBoot config file needs to be compiled with the +# CONFIG_BOOT_TRACE configuration option enabled. +# +# usage: +# cat uboot.trace | perl ./tools/bootgraph.pl > output.svg +# + +use strict; + +my %start; +my %end; +my %type; +my $done = 0; +my $maxtime = 0; +my $firsttime = 99999; +my $count = 0; +my $textoffset = hex('0x80008000'); +my $reloffset = $textoffset; +my %sym; + +if (!open FILE, "System.map") { + print STDERR < output.svg +END + exit 1; +} + +# Read in a map file to support in the printing of function names +while () { + my $line = $_; + if ($line =~ /([a-zA-Z0-9]+)\ [a-zA-Z] ([a-zA-Z0-0\_\.]+)/) { + $sym{$1} = $2; + } +} +close FILE; + +# Parse lines of UBoot's trace to generate a timeline +while (<>) { + my $line = $_; + + # Find start of init calls as instrumented in UBoot with DO_INITCALLXXX macros + if ($line =~ /([0-9\.]+)\] calling (0x[a-zA-Z0-9\_\.]+)/) { + my $func = sprintf("%x", (hex($2) - $reloffset + $textoffset)); + $func = $sym{$func}; + if ($done == 0) { + $start{$func} = $1; + $type{$func} = 0; + if ($1 < $firsttime) { + $firsttime = $1; + } + } + $count = $count + 1; + } + + # Find end of init calls as instrumented in UBoot with DO_INITCALLXXX macros + if ($line =~ /([0-9\.]+)\] initcall (0x[a-zA-Z0-9\_\.]+).*returned/) { + if ($done == 0) { + my $func = sprintf("%x", (hex($2) - $reloffset + $textoffset)); + $func = $sym{$func}; + $end{$func} = $1; + $maxtime = $1; + } + } + + # Determine where UBoot relocates itself in RAM + if ($line =~ /performing relocate to ram to (0x[a-zA-Z0-9\_\.]+)/) { + $reloffset = hex($1); + } + + # Stop scanning once we've left UBoot + if ($line =~ /Starting kernel /) { + $done = 1; + } +} + +# No data was captured +if ($count == 0) { + print STDERR < output.svg +END + exit 1; +} + +print " \n"; +print "\n"; + +my @styles; + +$styles[0] = "fill:rgb(0,0,255);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; +$styles[1] = "fill:rgb(0,255,0);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; +$styles[2] = "fill:rgb(255,0,20);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; +$styles[3] = "fill:rgb(255,255,20);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; +$styles[4] = "fill:rgb(255,0,255);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; +$styles[5] = "fill:rgb(0,255,255);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; +$styles[6] = "fill:rgb(0,128,255);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; +$styles[7] = "fill:rgb(0,255,128);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; +$styles[8] = "fill:rgb(255,0,128);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; +$styles[9] = "fill:rgb(255,255,128);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; +$styles[10] = "fill:rgb(255,128,255);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; +$styles[11] = "fill:rgb(128,255,255);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; + +my $style_wait = "fill:rgb(128,128,128);fill-opacity:0.5;stroke-width:0;stroke:rgb(0,0,0)"; + +my $mult = 1950.0 / ($maxtime - $firsttime); +my $threshold2 = ($maxtime - $firsttime) / 120.0; +my $threshold = $threshold2/10; +my $stylecounter = 0; +my @initcalls = sort { $start{$a} <=> $start{$b} } keys(%start); + +foreach my $key (@initcalls) { + my $duration = $end{$key} - $start{$key}; + + if ($duration >= $threshold) { + my ($s, $s2, $s3, $e, $w, $y, $y2, $style); + + $s = ($start{$key} - $firsttime) * $mult; + $s2 = $s + 6; + $s3 = $s + 1; + $e = ($end{$key} - $firsttime) * $mult; + $w = $e - $s; + + $y = 150; + $y2 = $y + 4; + + $style = $styles[$stylecounter]; + $stylecounter = $stylecounter + 1; + if ($stylecounter > 11) { + $stylecounter = 0; + }; + + if ($type{$key} == 1) { + $y = $y + 15; + print "\n"; + } else { + print "\n"; + if ($duration >= $threshold2) { + print "$key\n"; + } else { + print "$key\n"; + } + } + } +} + + +# print the time line on top +my $time = $firsttime; +my $step = ($maxtime - $firsttime) / 15; +while ($time < $maxtime) { + my $s3 = ($time - $firsttime) * $mult; + my $tm = int($time * 100) / 100.0; + print "$tm\n"; + $time = $time + $step; +} + +print "\n";