* [PATCH] fastboot: Add a script to visualize the kernel boot process / time
@ 2008-09-13 16:42 Arjan van de Ven
2008-09-14 13:18 ` Ingo Molnar
2008-09-15 12:59 ` Pádraig Brady
0 siblings, 2 replies; 6+ messages in thread
From: Arjan van de Ven @ 2008-09-13 16:42 UTC (permalink / raw
To: linux-kernel; +Cc: mingo
From: Arjan van de Ven <arjan@linux.intel.com>
Subject: [PATCH] fastboot: Add a script to visualize the kernel boot process / time
When optimizing the kernel boot time, it's very valuable to visualize
what is going on at which time. In addition, with the fastboot asynchronous
initcall level, it's very valuable to see which initcall gets run where
and when.
This patch adds a script to turn a dmesg into a SVG graph (that can be
shown with tools such as InkScape, Gimp or Firefox) and a small change
to the initcall code to print the PID of the thread calling the initcall
(so that the script can work out the parallelism).
Signed-off-by: Arjan van de Ven <arjan@linux.intel.com>
---
init/main.c | 3 +-
scripts/bootgraph.pl | 132 ++++++++++++++++++++++++++++++++++++++++++++++++++
2 files changed, 134 insertions(+), 1 deletions(-)
create mode 100644 scripts/bootgraph.pl
diff --git a/init/main.c b/init/main.c
index 73785a4..d74757c 100644
--- a/init/main.c
+++ b/init/main.c
@@ -702,7 +702,8 @@ int do_one_initcall(initcall_t fn)
int result;
if (initcall_debug) {
- print_fn_descriptor_symbol("calling %s\n", fn);
+ print_fn_descriptor_symbol("calling %s", fn);
+ printk(" @ %i\n", task_pid_nr(current));
t0 = ktime_get();
}
diff --git a/scripts/bootgraph.pl b/scripts/bootgraph.pl
new file mode 100644
index 0000000..54907d6
--- /dev/null
+++ b/scripts/bootgraph.pl
@@ -0,0 +1,132 @@
+#!/usr/bin/perl
+
+# Copyright 2007, Intel Corporation
+#
+# This file is part of the Linux kernel
+#
+# 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
+#
+# Authors:
+# Arjan van de Ven <arjan@linux.intel.com>
+
+
+#
+# This script turns a dmesg output 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 kernel needs to be compiled with the
+# CONFIG_PRINTK_TIME configuration option enabled, and with
+# "initcall_debug" passed on the kernel command line.
+#
+# usage:
+# dmesg | perl scripts/bootgraph.pl > output.svg
+#
+
+my @rows;
+my %start, %end, %row;
+my $done = 0;
+my $rowcount = 0;
+my $maxtime = 0;
+my $count = 0;
+while (<>) {
+ my $line = $_;
+ if ($line =~ /([0-9\.]+)\] calling ([a-zA-Z\_]+)\+/) {
+ my $func = $2;
+ if ($done == 0) {
+ $start{$func} = $1;
+ }
+ $row{$func} = 1;
+ if ($line =~ /\@ ([0-9]+)/) {
+ my $pid = $1;
+ if (!defined($rows[$pid])) {
+ $rowcount = $rowcount + 1;
+ $rows[$pid] = $rowcount;
+ }
+ $row{$func} = $rows[$pid];
+ }
+ $count = $count + 1;
+ }
+
+ if ($line =~ /([0-9\.]+)\] initcall ([a-zA-Z\_]+)\+.*returned/) {
+ if ($done == 0) {
+ $end{$2} = $1;
+ $maxtime = $1;
+ }
+ }
+ if ($line =~ /Write protecting the/) {
+ $done = 1;
+ }
+}
+
+if ($count == 0) {
+ print "No data found in the dmesg. Make sure CONFIG_PRINTK_TIME is enabled and\n";
+ print "that initcall_debug is passed on the kernel command line.\n\n";
+ print "Usage: \n";
+ print " dmesg | perl scripts/bootgraph.pl > output.svg\n\n";
+ exit;
+}
+
+print "<?xml version=\"1.0\" standalone=\"no\"?> \n";
+print "<svg width=\"1000\" height=\"100%\" version=\"1.1\" xmlns=\"http://www.w3.org/2000/svg\">\n";
+
+my @styles;
+
+$styles[0] = "fill:rgb(0,0,255);stroke-width:1;stroke:rgb(0,0,0)";
+$styles[1] = "fill:rgb(0,255,0);stroke-width:1;stroke:rgb(0,0,0)";
+$styles[2] = "fill:rgb(255,0,20);stroke-width:1;stroke:rgb(0,0,0)";
+$styles[3] = "fill:rgb(255,255,20);stroke-width:1;stroke:rgb(0,0,0)";
+$styles[4] = "fill:rgb(255,0,255);stroke-width:1;stroke:rgb(0,0,0)";
+$styles[5] = "fill:rgb(0,255,255);stroke-width:1;stroke:rgb(0,0,0)";
+
+my $mult = 950.0 / $maxtime;
+my $threshold = 0.0800 / $maxtime;
+my $stylecounter = 0;
+while (($key,$value) = each %start) {
+ my $duration = $end{$key} - $start{$key};
+
+ if ($duration >= $threshold) {
+ my $s, $s2, $e, $y;
+ $s = $value * $mult;
+ $s2 = $s + 6;
+ $e = $end{$key} * $mult;
+ $w = $e - $s;
+
+ $y = $row{$key} * 150;
+ $y2 = $y + 4;
+
+ $style = $styles[$stylecounter];
+ $stylecounter = $stylecounter + 1;
+ if ($stylecounter > 5) {
+ $stylecounter = 0;
+ };
+
+ print "<rect x=\"$s\" width=\"$w\" y=\"$y\" height=\"145\" style=\"$style\"/>\n";
+ print "<text transform=\"translate($s2,$y2) rotate(90)\">$key</text>\n";
+ }
+}
+
+
+# print the time line on top
+my $time = 0.0;
+while ($time < $maxtime) {
+ my $s2 = $time * $mult;
+ print "<text transform=\"translate($s2,89) rotate(90)\">$time</text>\n";
+ $time = $time + 0.1;
+}
+
+print "</svg>\n";
--
1.5.5.1
--
Arjan van de Ven Intel Open Source Technology Centre
For development, discussion and tips for power savings,
visit http://www.lesswatts.org
^ permalink raw reply related [flat|nested] 6+ messages in thread
* Re: [PATCH] fastboot: Add a script to visualize the kernel boot process / time
2008-09-13 16:42 [PATCH] fastboot: Add a script to visualize the kernel boot process / time Arjan van de Ven
@ 2008-09-14 13:18 ` Ingo Molnar
2008-09-15 12:59 ` Pádraig Brady
1 sibling, 0 replies; 6+ messages in thread
From: Ingo Molnar @ 2008-09-14 13:18 UTC (permalink / raw
To: Arjan van de Ven; +Cc: linux-kernel
* Arjan van de Ven <arjan@infradead.org> wrote:
> From: Arjan van de Ven <arjan@linux.intel.com>
> Subject: [PATCH] fastboot: Add a script to visualize the kernel boot process / time
applied to tip/fastboot - thanks Arjan.
Ingo
^ permalink raw reply [flat|nested] 6+ messages in thread
* Re: [PATCH] fastboot: Add a script to visualize the kernel boot process / time
2008-09-13 16:42 [PATCH] fastboot: Add a script to visualize the kernel boot process / time Arjan van de Ven
2008-09-14 13:18 ` Ingo Molnar
@ 2008-09-15 12:59 ` Pádraig Brady
2008-09-15 13:37 ` Arjan van de Ven
1 sibling, 1 reply; 6+ messages in thread
From: Pádraig Brady @ 2008-09-15 12:59 UTC (permalink / raw
To: Arjan van de Ven; +Cc: linux-kernel, mingo
Arjan van de Ven wrote:
>
> From: Arjan van de Ven <arjan@linux.intel.com>
> Subject: [PATCH] fastboot: Add a script to visualize the kernel boot process / time
Have you example output anywhere?
^ permalink raw reply [flat|nested] 6+ messages in thread
* Re: [PATCH] fastboot: Add a script to visualize the kernel boot process / time
2008-09-15 12:59 ` Pádraig Brady
@ 2008-09-15 13:37 ` Arjan van de Ven
2008-09-15 14:27 ` Pádraig Brady
0 siblings, 1 reply; 6+ messages in thread
From: Arjan van de Ven @ 2008-09-15 13:37 UTC (permalink / raw
To: Pádraig Brady; +Cc: linux-kernel, mingo
On Mon, 15 Sep 2008 13:59:49 +0100
Pádraig Brady <P@draigBrady.com> wrote:
> Arjan van de Ven wrote:
> >
> > From: Arjan van de Ven <arjan@linux.intel.com>
> > Subject: [PATCH] fastboot: Add a script to visualize the kernel
> > boot process / time
>
> Have you example output anywhere?
eh sure
http://www.fenrus.org/before.svg
http://www.fenrus.org/after.svg
(before and after another patch ;-)
--
Arjan van de Ven Intel Open Source Technology Centre
For development, discussion and tips for power savings,
visit http://www.lesswatts.org
^ permalink raw reply [flat|nested] 6+ messages in thread
* Re: [PATCH] fastboot: Add a script to visualize the kernel boot process / time
2008-09-15 13:37 ` Arjan van de Ven
@ 2008-09-15 14:27 ` Pádraig Brady
2008-10-02 11:43 ` Pádraig Brady
0 siblings, 1 reply; 6+ messages in thread
From: Pádraig Brady @ 2008-09-15 14:27 UTC (permalink / raw
To: Arjan van de Ven; +Cc: linux-kernel, mingo
Arjan van de Ven wrote:
> On Mon, 15 Sep 2008 13:59:49 +0100
> Pádraig Brady <P@draigBrady.com> wrote:
>
>> Arjan van de Ven wrote:
>>> From: Arjan van de Ven <arjan@linux.intel.com>
>>> Subject: [PATCH] fastboot: Add a script to visualize the kernel
>>> boot process / time
>> Have you example output anywhere?
>
> eh sure
>
> http://www.fenrus.org/before.svg
> http://www.fenrus.org/after.svg
>
> (before and after another patch ;-)
Nice times :)
I guess we'll hear more about this on Thursday morning:
http://linuxplumbersconf.org/program/speakers/getspeaker.php?speaker=avandeven.txt
Pádraig.
^ permalink raw reply [flat|nested] 6+ messages in thread
* Re: [PATCH] fastboot: Add a script to visualize the kernel boot process / time
2008-09-15 14:27 ` Pádraig Brady
@ 2008-10-02 11:43 ` Pádraig Brady
0 siblings, 0 replies; 6+ messages in thread
From: Pádraig Brady @ 2008-10-02 11:43 UTC (permalink / raw
To: Arjan van de Ven; +Cc: linux-kernel, mingo
Pádraig Brady wrote:
> Arjan van de Ven wrote:
>>
>> http://www.fenrus.org/before.svg
>> http://www.fenrus.org/after.svg
>>
>> (before and after another patch ;-)
>
> Nice times :)
> I guess we'll hear more about this on Thursday morning:
> http://linuxplumbersconf.org/program/speakers/getspeaker.php?speaker=avandeven.txt
For the archives, there is some public info on this now:
http://lwn.net/Articles/299483/
cheers,
Pádraig.
^ permalink raw reply [flat|nested] 6+ messages in thread
end of thread, other threads:[~2008-10-02 11:44 UTC | newest]
Thread overview: 6+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2008-09-13 16:42 [PATCH] fastboot: Add a script to visualize the kernel boot process / time Arjan van de Ven
2008-09-14 13:18 ` Ingo Molnar
2008-09-15 12:59 ` Pádraig Brady
2008-09-15 13:37 ` Arjan van de Ven
2008-09-15 14:27 ` Pádraig Brady
2008-10-02 11:43 ` Pádraig Brady
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for read-only IMAP folder(s) and NNTP newsgroup(s).