Commit | Line | Data |
---|---|---|
cb77f0d6 | 1 | #!/usr/bin/env perl |
c2e30119 | 2 | # SPDX-License-Identifier: GPL-2.0-only |
aa5d9151 AV |
3 | |
4 | # Copyright 2008, Intel Corporation | |
5 | # | |
6 | # This file is part of the Linux kernel | |
7 | # | |
aa5d9151 AV |
8 | # Authors: |
9 | # Arjan van de Ven <arjan@linux.intel.com> | |
10 | ||
11 | ||
12 | # | |
13 | # This script turns a dmesg output into a SVG graphic that shows which | |
14 | # functions take how much time. You can view SVG graphics with various | |
15 | # programs, including Inkscape, The Gimp and Firefox. | |
16 | # | |
17 | # | |
18 | # For this script to work, the kernel needs to be compiled with the | |
19 | # CONFIG_PRINTK_TIME configuration option enabled, and with | |
20 | # "initcall_debug" passed on the kernel command line. | |
21 | # | |
22 | # usage: | |
23 | # dmesg | perl scripts/bootgraph.pl > output.svg | |
24 | # | |
25 | ||
2a813f8c | 26 | use strict; |
67554faa FF |
27 | use Getopt::Long; |
28 | my $header = 0; | |
29 | ||
30 | sub help { | |
31 | my $text = << "EOM"; | |
32 | Usage: | |
33 | 1) dmesg | perl scripts/bootgraph.pl [OPTION] > output.svg | |
34 | 2) perl scripts/bootgraph.pl -h | |
35 | ||
36 | Options: | |
37 | -header Insert kernel version and date | |
38 | EOM | |
39 | my $std=shift; | |
40 | if ($std == 1) { | |
41 | print STDERR $text; | |
42 | } else { | |
43 | print $text; | |
44 | } | |
45 | exit; | |
46 | } | |
47 | ||
48 | GetOptions( | |
49 | 'h|help' =>\&help, | |
50 | 'header' =>\$header | |
51 | ); | |
2a813f8c AJ |
52 | |
53 | my %start; | |
54 | my %end; | |
d3f8ddea | 55 | my %type; |
aa5d9151 | 56 | my $done = 0; |
aa5d9151 | 57 | my $maxtime = 0; |
c443453c | 58 | my $firsttime = 99999; |
aa5d9151 | 59 | my $count = 0; |
ddc7a01a | 60 | my %pids; |
d3f8ddea | 61 | my %pidctr; |
ddc7a01a | 62 | |
67554faa FF |
63 | my $headerstep = 20; |
64 | my $xheader = 15; | |
65 | my $yheader = 25; | |
66 | my $cyheader = 0; | |
67 | ||
aa5d9151 AV |
68 | while (<>) { |
69 | my $line = $_; | |
0bb98e23 | 70 | if ($line =~ /([0-9\.]+)\] calling ([a-zA-Z0-9\_\.]+)\+/) { |
aa5d9151 AV |
71 | my $func = $2; |
72 | if ($done == 0) { | |
73 | $start{$func} = $1; | |
d3f8ddea | 74 | $type{$func} = 0; |
80a398a5 AV |
75 | if ($1 < $firsttime) { |
76 | $firsttime = $1; | |
77 | } | |
aa5d9151 | 78 | } |
aa5d9151 | 79 | if ($line =~ /\@ ([0-9]+)/) { |
ddc7a01a | 80 | $pids{$func} = $1; |
aa5d9151 AV |
81 | } |
82 | $count = $count + 1; | |
83 | } | |
84 | ||
d3f8ddea AV |
85 | if ($line =~ /([0-9\.]+)\] async_waiting @ ([0-9]+)/) { |
86 | my $pid = $2; | |
87 | my $func; | |
88 | if (!defined($pidctr{$pid})) { | |
89 | $func = "wait_" . $pid . "_1"; | |
90 | $pidctr{$pid} = 1; | |
91 | } else { | |
92 | $pidctr{$pid} = $pidctr{$pid} + 1; | |
93 | $func = "wait_" . $pid . "_" . $pidctr{$pid}; | |
94 | } | |
95 | if ($done == 0) { | |
96 | $start{$func} = $1; | |
97 | $type{$func} = 1; | |
98 | if ($1 < $firsttime) { | |
99 | $firsttime = $1; | |
100 | } | |
101 | } | |
102 | $pids{$func} = $pid; | |
103 | $count = $count + 1; | |
104 | } | |
105 | ||
0bb98e23 | 106 | if ($line =~ /([0-9\.]+)\] initcall ([a-zA-Z0-9\_\.]+)\+.*returned/) { |
aa5d9151 AV |
107 | if ($done == 0) { |
108 | $end{$2} = $1; | |
109 | $maxtime = $1; | |
110 | } | |
111 | } | |
d3f8ddea AV |
112 | |
113 | if ($line =~ /([0-9\.]+)\] async_continuing @ ([0-9]+)/) { | |
114 | my $pid = $2; | |
115 | my $func = "wait_" . $pid . "_" . $pidctr{$pid}; | |
116 | $end{$func} = $1; | |
117 | $maxtime = $1; | |
118 | } | |
aa5d9151 AV |
119 | if ($line =~ /Write protecting the/) { |
120 | $done = 1; | |
121 | } | |
80a398a5 AV |
122 | if ($line =~ /Freeing unused kernel memory/) { |
123 | $done = 1; | |
124 | } | |
aa5d9151 AV |
125 | } |
126 | ||
127 | if ($count == 0) { | |
d1aaf8cf SH |
128 | print STDERR <<END; |
129 | No data found in the dmesg. Make sure that 'printk.time=1' and | |
130 | 'initcall_debug' are passed on the kernel command line. | |
d1aaf8cf | 131 | END |
67554faa | 132 | help(1); |
d1aaf8cf | 133 | exit 1; |
aa5d9151 AV |
134 | } |
135 | ||
136 | print "<?xml version=\"1.0\" standalone=\"no\"?> \n"; | |
40c8c85a | 137 | print "<svg width=\"2000\" height=\"100%\" version=\"1.1\" xmlns=\"http://www.w3.org/2000/svg\">\n"; |
aa5d9151 | 138 | |
67554faa FF |
139 | |
140 | if ($header) { | |
141 | my $version = `uname -a`; | |
142 | my $date = `date`; | |
143 | print "<text transform=\"translate($xheader,$yheader)\">Kernel version: $version</text>\n"; | |
144 | $cyheader = $yheader+$headerstep; | |
145 | print "<text transform=\"translate($xheader,$cyheader)\">Date: $date</text>\n"; | |
146 | } | |
147 | ||
aa5d9151 AV |
148 | my @styles; |
149 | ||
150 | $styles[0] = "fill:rgb(0,0,255);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; | |
151 | $styles[1] = "fill:rgb(0,255,0);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; | |
152 | $styles[2] = "fill:rgb(255,0,20);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; | |
153 | $styles[3] = "fill:rgb(255,255,20);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; | |
154 | $styles[4] = "fill:rgb(255,0,255);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; | |
155 | $styles[5] = "fill:rgb(0,255,255);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; | |
156 | $styles[6] = "fill:rgb(0,128,255);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; | |
157 | $styles[7] = "fill:rgb(0,255,128);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; | |
158 | $styles[8] = "fill:rgb(255,0,128);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; | |
159 | $styles[9] = "fill:rgb(255,255,128);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; | |
160 | $styles[10] = "fill:rgb(255,128,255);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; | |
161 | $styles[11] = "fill:rgb(128,255,255);fill-opacity:0.5;stroke-width:1;stroke:rgb(0,0,0)"; | |
162 | ||
d3f8ddea AV |
163 | my $style_wait = "fill:rgb(128,128,128);fill-opacity:0.5;stroke-width:0;stroke:rgb(0,0,0)"; |
164 | ||
40c8c85a AV |
165 | my $mult = 1950.0 / ($maxtime - $firsttime); |
166 | my $threshold2 = ($maxtime - $firsttime) / 120.0; | |
167 | my $threshold = $threshold2/10; | |
aa5d9151 | 168 | my $stylecounter = 0; |
ddc7a01a FW |
169 | my %rows; |
170 | my $rowscount = 1; | |
06d1cd26 | 171 | my @initcalls = sort { $start{$a} <=> $start{$b} } keys(%start); |
68f96c0c SH |
172 | |
173 | foreach my $key (@initcalls) { | |
aa5d9151 AV |
174 | my $duration = $end{$key} - $start{$key}; |
175 | ||
176 | if ($duration >= $threshold) { | |
40c8c85a | 177 | my ($s, $s2, $s3, $e, $w, $y, $y2, $style); |
2a813f8c | 178 | my $pid = $pids{$key}; |
07d18904 FW |
179 | |
180 | if (!defined($rows{$pid})) { | |
181 | $rows{$pid} = $rowscount; | |
182 | $rowscount = $rowscount + 1; | |
183 | } | |
06d1cd26 | 184 | $s = ($start{$key} - $firsttime) * $mult; |
aa5d9151 | 185 | $s2 = $s + 6; |
40c8c85a | 186 | $s3 = $s + 1; |
80a398a5 | 187 | $e = ($end{$key} - $firsttime) * $mult; |
aa5d9151 AV |
188 | $w = $e - $s; |
189 | ||
ddc7a01a | 190 | $y = $rows{$pid} * 150; |
aa5d9151 AV |
191 | $y2 = $y + 4; |
192 | ||
193 | $style = $styles[$stylecounter]; | |
194 | $stylecounter = $stylecounter + 1; | |
195 | if ($stylecounter > 11) { | |
196 | $stylecounter = 0; | |
197 | }; | |
198 | ||
d3f8ddea AV |
199 | if ($type{$key} == 1) { |
200 | $y = $y + 15; | |
201 | print "<rect x=\"$s\" width=\"$w\" y=\"$y\" height=\"115\" style=\"$style_wait\"/>\n"; | |
40c8c85a | 202 | } else { |
d3f8ddea AV |
203 | print "<rect x=\"$s\" width=\"$w\" y=\"$y\" height=\"145\" style=\"$style\"/>\n"; |
204 | if ($duration >= $threshold2) { | |
205 | print "<text transform=\"translate($s2,$y2) rotate(90)\">$key</text>\n"; | |
206 | } else { | |
207 | print "<text transform=\"translate($s3,$y2) rotate(90)\" font-size=\"3pt\">$key</text>\n"; | |
208 | } | |
40c8c85a | 209 | } |
aa5d9151 AV |
210 | } |
211 | } | |
212 | ||
213 | ||
214 | # print the time line on top | |
80a398a5 AV |
215 | my $time = $firsttime; |
216 | my $step = ($maxtime - $firsttime) / 15; | |
aa5d9151 | 217 | while ($time < $maxtime) { |
2a813f8c | 218 | my $s3 = ($time - $firsttime) * $mult; |
80a398a5 | 219 | my $tm = int($time * 100) / 100.0; |
2a813f8c | 220 | print "<text transform=\"translate($s3,89) rotate(90)\">$tm</text>\n"; |
80a398a5 | 221 | $time = $time + $step; |
aa5d9151 AV |
222 | } |
223 | ||
224 | print "</svg>\n"; |