analyze.c revision 4387795e0e3adcdfbfac4d83210ff53aa7c038c0
/*-*- Mode: C; c-basic-offset: 8; indent-tabs-mode: nil -*-*/
/***
This file is part of systemd.
Copyright 2010-2013 Lennart Poettering
Copyright 2013 Simon Peeters
under the terms of the GNU Lesser General Public License as published by
the Free Software Foundation; either version 2.1 of the License, or
(at your option) any later version.
systemd 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
Lesser General Public License for more details.
You should have received a copy of the GNU Lesser General Public License
along with systemd; If not, see <http://www.gnu.org/licenses/>.
***/
#include <stdio.h>
#include <stdlib.h>
#include <getopt.h>
#include <locale.h>
#include "sd-bus.h"
#include "bus-util.h"
#include "bus-error.h"
#include "log.h"
#include "build.h"
#include "util.h"
#include "strxcpyx.h"
#include "strv.h"
#include "unit-name.h"
#include "special.h"
#include "hashmap.h"
#include "pager.h"
#include "analyze-verify.h"
#include "terminal-util.h"
#define SCALE_Y (20.0)
svg(" <rect class=\"%s\" x=\"%.03f\" y=\"%.03f\" width=\"%.03f\" height=\"%.03f\" />\n", \
(class), \
do { \
svg(" <text class=\"%s\" x=\"%.03f\" y=\"%.03f\">", (b) ? "left" : "right", SCALE_X * (x) + (b ? 5.0 : -5.0), SCALE_Y * (y) + 14.0); \
svg("</text>\n"); \
} while(false)
static enum dot {
static char** arg_dot_from_patterns = NULL;
static char** arg_dot_to_patterns = NULL;
static bool arg_no_pager = false;
static bool arg_user = false;
static bool arg_man = true;
struct boot_times {
/*
* If we're analyzing the user instance, all timestamps will be offset
* by its own start-up timestamp, which may be arbitrarily big.
* With "plot", this causes arbitrarily wide output SVG files which almost
* completely consist of empty space. Thus we cancel out this offset.
*
* This offset is subtracted from times above by acquire_boot_times(),
* but it still needs to be subtracted from unit-specific timestamps
* (so it is stored here for reference).
*/
};
struct unit_times {
char *name;
};
struct host_info {
char *hostname;
char *kernel_name;
char *kernel_release;
char *kernel_version;
char *os_pretty_name;
char *virtualization;
char *architecture;
};
static void pager_open_if_enabled(void) {
if (arg_no_pager)
return;
pager_open(false);
}
static int bus_get_uint64_property(sd_bus *bus, const char *path, const char *interface, const char *property, uint64_t *val) {
int r;
bus,
"org.freedesktop.systemd1",
path,
&error,
't', val);
if (r < 0) {
return r;
}
return 0;
}
static int bus_get_unit_property_strv(sd_bus *bus, const char *path, const char *property, char ***strv) {
int r;
bus,
"org.freedesktop.systemd1",
path,
"org.freedesktop.systemd1.Unit",
&error,
strv);
if (r < 0) {
return r;
}
return 0;
}
static int compare_unit_time(const void *a, const void *b) {
((struct unit_times *)a)->time);
}
static int compare_unit_start(const void *a, const void *b) {
((struct unit_times *)b)->activating);
}
static void free_unit_times(struct unit_times *t, unsigned n) {
struct unit_times *p;
for (p = t; p < t + n; p++)
free(t);
}
assert(a);
if (*a > 0) {
assert(*a >= b);
*a -= b;
}
}
static struct boot_times times;
static bool cached = false;
if (cached)
goto finish;
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"FirmwareTimestampMonotonic",
×.firmware_time) < 0 ||
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"LoaderTimestampMonotonic",
×.loader_time) < 0 ||
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"KernelTimestamp",
×.kernel_time) < 0 ||
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"InitRDTimestampMonotonic",
×.initrd_time) < 0 ||
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"UserspaceTimestampMonotonic",
×.userspace_time) < 0 ||
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"FinishTimestampMonotonic",
×.finish_time) < 0 ||
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"SecurityStartTimestampMonotonic",
×.security_start_time) < 0 ||
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"SecurityFinishTimestampMonotonic",
×.security_finish_time) < 0 ||
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"GeneratorsStartTimestampMonotonic",
×.generators_start_time) < 0 ||
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"GeneratorsFinishTimestampMonotonic",
×.generators_finish_time) < 0 ||
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"UnitsLoadStartTimestampMonotonic",
×.unitsload_start_time) < 0 ||
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"UnitsLoadFinishTimestampMonotonic",
×.unitsload_finish_time) < 0)
return -EIO;
if (times.finish_time <= 0) {
log_error("Bootup is not yet finished. Please try again later.");
return -EINPROGRESS;
}
if (arg_user) {
/*
* User-instance-specific timestamps processing
* (see comment to reverse_offset in struct boot_times).
*/
times.firmware_time = times.loader_time = times.kernel_time = times.initrd_time = times.userspace_time = 0;
} else {
if (times.initrd_time)
else
}
cached = true;
return 0;
}
}
int r, c = 0;
UnitInfo u;
if (r < 0)
goto fail;
r = sd_bus_call_method(
bus,
"org.freedesktop.systemd1",
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"ListUnits",
NULL);
if (r < 0) {
goto fail;
}
if (r < 0) {
goto fail;
}
while ((r = bus_parse_unit_info(reply, &u)) > 0) {
struct unit_times *t;
r = log_oom();
goto fail;
}
t = unit_times+c;
"org.freedesktop.systemd1.Unit",
"InactiveExitTimestampMonotonic",
&t->activating) < 0 ||
"org.freedesktop.systemd1.Unit",
"ActiveEnterTimestampMonotonic",
&t->activated) < 0 ||
"org.freedesktop.systemd1.Unit",
"ActiveExitTimestampMonotonic",
&t->deactivating) < 0 ||
"org.freedesktop.systemd1.Unit",
"InactiveEnterTimestampMonotonic",
&t->deactivated) < 0) {
r = -EIO;
goto fail;
}
if (t->activated >= t->activating)
else if (t->deactivated >= t->activating)
else
t->time = 0;
if (t->activating == 0)
continue;
r = log_oom();
goto fail;
}
c++;
}
if (r < 0) {
goto fail;
}
*out = unit_times;
return c;
fail:
if (unit_times)
free_unit_times(unit_times, (unsigned) c);
return r;
}
int r;
static const struct bus_properties_map hostname_map[] = {
{}
};
static const struct bus_properties_map manager_map[] = {
{}
};
if (!host)
return log_oom();
"org.freedesktop.hostname1",
"/org/freedesktop/hostname1",
host);
if (r < 0)
goto fail;
"org.freedesktop.systemd1",
"/org/freedesktop/systemd1",
host);
if (r < 0)
goto fail;
return 0;
fail:
return r;
}
char ts[FORMAT_TIMESPAN_MAX];
struct boot_times *t;
static char buf[4096];
char *ptr;
int r;
r = acquire_boot_times(bus, &t);
if (r < 0)
return r;
if (t->firmware_time)
size = strpcpyf(&ptr, size, "%s (firmware) + ", format_timespan(ts, sizeof(ts), t->firmware_time - t->loader_time, USEC_PER_MSEC));
if (t->loader_time)
size = strpcpyf(&ptr, size, "%s (loader) + ", format_timespan(ts, sizeof(ts), t->loader_time, USEC_PER_MSEC));
if (t->kernel_time)
size = strpcpyf(&ptr, size, "%s (kernel) + ", format_timespan(ts, sizeof(ts), t->kernel_done_time, USEC_PER_MSEC));
if (t->initrd_time > 0)
size = strpcpyf(&ptr, size, "%s (initrd) + ", format_timespan(ts, sizeof(ts), t->userspace_time - t->initrd_time, USEC_PER_MSEC));
size = strpcpyf(&ptr, size, "%s (userspace) ", format_timespan(ts, sizeof(ts), t->finish_time - t->userspace_time, USEC_PER_MSEC));
strpcpyf(&ptr, size, "= %s", format_timespan(ts, sizeof(ts), t->firmware_time + t->finish_time, USEC_PER_MSEC));
if (!ptr)
return log_oom();
return 0;
}
long long i;
/* outside box, fill */
svg("<rect class=\"box\" x=\"0\" y=\"0\" width=\"%.03f\" height=\"%.03f\" />\n",
/* lines for each second */
if (i % 5000000 == 0)
svg(" <line class=\"sec5\" x1=\"%.03f\" y1=\"0\" x2=\"%.03f\" y2=\"%.03f\" />\n"
" <text class=\"sec\" x=\"%.03f\" y=\"%.03f\" >%.01fs</text>\n",
else if (i % 1000000 == 0)
svg(" <line class=\"sec1\" x1=\"%.03f\" y1=\"0\" x2=\"%.03f\" y2=\"%.03f\" />\n"
" <text class=\"sec\" x=\"%.03f\" y=\"%.03f\" >%.01fs</text>\n",
else
svg(" <line class=\"sec01\" x1=\"%.03f\" y1=\"0\" x2=\"%.03f\" y2=\"%.03f\" />\n",
}
}
struct unit_times *times;
struct boot_times *boot;
int n, m = 1, y=0;
double width;
struct unit_times *u;
if (n < 0)
return n;
if (n < 0)
return n;
if (n < 0)
return n;
if (n <= 0)
goto out;
if (width < 800.0)
width = 800.0;
m++;
if (boot->loader_time) {
m++;
if (width < 1000.0)
width = 1000.0;
}
if (boot->initrd_time)
m++;
if (boot->kernel_time)
m++;
double text_start, text_width;
continue;
}
/* If the text cannot fit on the left side then
* increase the svg width so it fits on the right.
* TODO: calculate the text width more accurately */
&& u->activated == 0 && u->deactivating == 0)
m++;
}
svg("<?xml version=\"1.0\" standalone=\"no\"?>\n"
"<!DOCTYPE svg PUBLIC \"-//W3C//DTD SVG 1.1//EN\" "
svg("<svg width=\"%.0fpx\" height=\"%.0fpx\" version=\"1.1\" "
"xmlns=\"http://www.w3.org/2000/svg\">\n\n",
/* write some basic info as a comment, including some help */
svg("<!-- This file is a systemd-analyze SVG file. It is best rendered in a -->\n"
"<!-- browser such as Chrome, Chromium or Firefox. Other applications -->\n"
"<!-- that render these files properly but much slower are ImageMagick, -->\n"
"<!-- gimp, inkscape, etc. To display the files on your system, just -->\n"
"<!-- point your browser to this file. -->\n\n"
"<!-- This plot was generated by systemd-analyze version %-16.16s -->\n\n", VERSION);
/* style sheet */
" rect { stroke-width: 1; stroke-opacity: 0; }\n"
" rect.background { fill: rgb(255,255,255); }\n"
" rect.activating { fill: rgb(255,0,0); fill-opacity: 0.7; }\n"
" rect.active { fill: rgb(200,150,150); fill-opacity: 0.7; }\n"
" rect.deactivating { fill: rgb(150,100,100); fill-opacity: 0.7; }\n"
" rect.kernel { fill: rgb(150,150,150); fill-opacity: 0.7; }\n"
" rect.initrd { fill: rgb(150,150,150); fill-opacity: 0.7; }\n"
" rect.firmware { fill: rgb(150,150,150); fill-opacity: 0.7; }\n"
" rect.loader { fill: rgb(150,150,150); fill-opacity: 0.7; }\n"
" rect.userspace { fill: rgb(150,150,150); fill-opacity: 0.7; }\n"
" rect.security { fill: rgb(144,238,144); fill-opacity: 0.7; }\n"
" rect.generators { fill: rgb(102,204,255); fill-opacity: 0.7; }\n"
" rect.unitsload { fill: rgb( 82,184,255); fill-opacity: 0.7; }\n"
" rect.box { fill: rgb(240,240,240); stroke: rgb(192,192,192); }\n"
" line { stroke: rgb(64,64,64); stroke-width: 1; }\n"
"// line.sec1 { }\n"
" line.sec5 { stroke-width: 2; }\n"
" line.sec01 { stroke: rgb(224,224,224); stroke-width: 1; }\n"
" text { font-family: Verdana, Helvetica; font-size: 14px; }\n"
" text.left { font-family: Verdana, Helvetica; font-size: 14px; text-anchor: start; }\n"
" text.right { font-family: Verdana, Helvetica; font-size: 14px; text-anchor: end; }\n"
" text.sec { font-size: 10px; }\n"
" ]]>\n </style>\n</defs>\n\n");
svg("<rect class=\"background\" width=\"100%%\" height=\"100%%\" />\n");
svg("<text x=\"20\" y=\"30\">%s %s (%s %s %s) %s %s</text>",
if (boot->firmware_time) {
y++;
}
if (boot->loader_time) {
y++;
}
if (boot->kernel_time) {
svg_text(true, 0, y, "kernel");
y++;
}
if (boot->initrd_time) {
y++;
}
y++;
char ts[FORMAT_TIMESPAN_MAX];
bool b;
if (!u->name)
continue;
/* place the text on the left if we have passed the half of the svg width */
if (u->time)
else
y++;
}
svg("</g>\n");
/* Legend */
svg("<g transform=\"translate(20,100)\">\n");
y++;
y++;
y++;
y++;
y++;
y++;
y++;
svg("</g>\n\n");
svg("</svg>\n");
free_unit_times(times, (unsigned) n);
n = 0;
out:
return n;
}
unsigned int i;
for (i = level; i != 0; i--)
if (times) {
printf("%s @%s", name, format_timespan(ts, sizeof(ts), times->activated - boot->userspace_time, USEC_PER_MSEC));
else
} else
printf("\n");
return 0;
}
return -ENOMEM;
}
static Hashmap *unit_times_hashmap;
struct unit_times *times;
if (times)
if (times)
}
unsigned int branches) {
char **c;
int r = 0;
usec_t service_longest = 0;
int to_print = 0;
struct unit_times *times;
struct boot_times *boot;
return log_oom();
if (r < 0)
return r;
if (r < 0)
return r;
STRV_FOREACH(c, deps) {
if (times
|| service_longest == 0)) {
break;
}
}
if (service_longest == 0 )
return r;
STRV_FOREACH(c, deps) {
to_print++;
}
}
if (!to_print)
return r;
STRV_FOREACH(c, deps) {
if (!times
continue;
to_print--;
if (r < 0)
return r;
if (strv_contains(*units, *c)) {
if (r < 0)
return r;
continue;
}
if (r < 0)
return r;
if (!to_print)
break;
}
return 0;
}
char ts[FORMAT_TIMESPAN_MAX];
struct unit_times *times;
int r;
const char *id;
struct boot_times *boot;
return -ENOMEM;
r = sd_bus_get_property(
bus,
"org.freedesktop.systemd1",
path,
"org.freedesktop.systemd1.Unit",
"Id",
&error,
&reply,
"s");
if (r < 0) {
return r;
}
if (r < 0)
return bus_log_parse_error(r);
if (r < 0)
return r;
if (times) {
printf("%s @%s\n", id, format_timespan(ts, sizeof(ts), times->activated - boot->userspace_time, USEC_PER_MSEC));
else
}
}
struct unit_times *times;
unsigned int i;
Hashmap *h;
int n, r;
if (n <= 0)
return n;
h = hashmap_new(&string_hash_ops);
if (!h)
return -ENOMEM;
for (i = 0; i < (unsigned)n; i++) {
if (r < 0)
return r;
}
unit_times_hashmap = h;
puts("The time after the unit is active or started is printed after the \"@\" character.\n"
"The time the unit takes to start is printed after the \"+\" character.\n");
if (!strv_isempty(names)) {
char **name;
} else
hashmap_free(h);
free_unit_times(times, (unsigned) n);
return 0;
}
struct unit_times *times;
unsigned i;
int n;
if (n <= 0)
return n;
for (i = 0; i < (unsigned) n; i++) {
char ts[FORMAT_TIMESPAN_MAX];
}
free_unit_times(times, (unsigned) n);
return 0;
}
int r;
if (r < 0)
return r;
return 0;
}
static int graph_one_property(sd_bus *bus, const UnitInfo *u, const char* prop, const char *color, char* patterns[], char* from_patterns[], char* to_patterns[]) {
char **unit;
int r;
bool match_patterns;
assert(u);
if (!strv_isempty(from_patterns) &&
!match_patterns &&
return 0;
if (r < 0)
return r;
bool match_patterns2;
if (!strv_isempty(to_patterns) &&
!match_patterns2 &&
continue;
continue;
}
return 0;
}
static int graph_one(sd_bus *bus, const UnitInfo *u, char *patterns[], char *from_patterns[], char *to_patterns[]) {
int r;
assert(u);
if (r < 0)
return r;
}
if (r < 0)
return r;
r = graph_one_property(bus, u, "RequiresOverridable", "black", patterns, from_patterns, to_patterns);
if (r < 0)
return r;
r = graph_one_property(bus, u, "RequisiteOverridable", "darkblue", patterns, from_patterns, to_patterns);
if (r < 0)
return r;
if (r < 0)
return r;
if (r < 0)
return r;
if (r < 0)
return r;
}
return 0;
}
char **pattern;
int r;
return log_oom();
if (string_is_glob(*pattern))
continue;
if (!unit)
return log_oom();
bus,
"org.freedesktop.systemd1",
unit,
"org.freedesktop.systemd1.Unit",
"Id",
&error,
&unit_id);
if (r < 0)
return log_oom();
}
}
*ret = expanded_patterns;
return 0;
}
int r;
UnitInfo u;
if (r < 0)
return r;
if (r < 0)
return r;
if (r < 0)
return r;
r = sd_bus_call_method(
bus,
"org.freedesktop.systemd1",
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"ListUnits",
&error,
&reply,
"");
if (r < 0) {
return r;
}
if (r < 0)
return bus_log_parse_error(r);
printf("digraph systemd {\n");
while ((r = bus_parse_unit_info(reply, &u)) > 0) {
if (r < 0)
return r;
}
if (r < 0)
return bus_log_parse_error(r);
printf("}\n");
log_info(" Color legend: black = Requires\n"
" dark blue = Requisite\n"
" dark grey = Wants\n"
" red = Conflicts\n"
" green = After\n");
if (on_tty())
log_notice("-- You probably want to process this output with graphviz' dot tool.\n"
"-- Try a shell pipeline like 'systemd-analyze dot | dot -Tsvg > systemd.svg'!\n");
return 0;
}
int r;
if (!strv_isempty(args)) {
log_error("Too many arguments.");
return -E2BIG;
}
r = sd_bus_call_method(
bus,
"org.freedesktop.systemd1",
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"Dump",
&error,
&reply,
"");
if (r < 0) {
return r;
}
if (r < 0)
return bus_log_parse_error(r);
return 0;
}
int r;
log_error("This command expects one argument only.");
return -E2BIG;
}
r = sd_bus_set_property(
bus,
"org.freedesktop.systemd1",
"/org/freedesktop/systemd1",
"org.freedesktop.systemd1.Manager",
"LogLevel",
&error,
"s",
args[0]);
if (r < 0) {
return -EIO;
}
return 0;
}
static void help(void) {
printf("%s [OPTIONS...] {COMMAND} ...\n\n"
"Profile systemd, show unit dependencies, check unit files.\n\n"
" -h --help Show this help\n"
" --version Show package version\n"
" --no-pager Do not pipe output into a pager\n"
" --system Operate on system systemd instance\n"
" --user Operate on user systemd instance\n"
" -H --host=[USER@]HOST Operate on remote host\n"
" -M --machine=CONTAINER Operate on local container\n"
" --order Show only order in the graph\n"
" --require Show only requirement in the graph\n"
" --from-pattern=GLOB Show only origins in the graph\n"
" --to-pattern=GLOB Show only destinations in the graph\n"
" --fuzz=SECONDS Also print also services which finished SECONDS\n"
" earlier than the latest in the branch\n"
" --man[=BOOL] Do [not] check for existence of man pages\n\n"
"Commands:\n"
" time Print time spent in the kernel\n"
" blame Print list of running units ordered by time to init\n"
" critical-chain Print a tree of the time critical chain of units\n"
" plot Output SVG graphic showing service initialization\n"
" dot Output dependency graph in dot(1) format\n"
" set-log-level LEVEL Set logging threshold for systemd\n"
" dump Output state serialization of service manager\n"
" verify FILE... Check unit files for correctness\n"
/* When updating this list, including descriptions, apply
* changes to shell-completion/bash/systemd-analyze and
* shell-completion/zsh/_systemd-analyze too. */
}
enum {
ARG_VERSION = 0x100,
};
{}
};
int r, c;
switch (c) {
case 'h':
help();
return 0;
case ARG_VERSION:
return 0;
case ARG_USER:
arg_user = true;
break;
case ARG_SYSTEM:
arg_user = false;
break;
case ARG_ORDER:
break;
case ARG_REQUIRE:
break;
case ARG_DOT_FROM_PATTERN:
return log_oom();
break;
case ARG_DOT_TO_PATTERN:
return log_oom();
break;
case ARG_FUZZ:
if (r < 0)
return r;
break;
case ARG_NO_PAGER:
arg_no_pager = true;
break;
case 'H':
break;
case 'M':
break;
case ARG_MAN:
if (optarg) {
r = parse_boolean(optarg);
if (r < 0) {
log_error("Failed to parse --man= argument.");
return -EINVAL;
}
arg_man = !!r;
} else
arg_man = true;
break;
case '?':
return -EINVAL;
default:
assert_not_reached("Unhandled option code.");
}
return 1; /* work to do */
}
int r;
log_open();
if (r <= 0)
goto finish;
arg_man);
else {
if (r < 0) {
log_error_errno(r, "Failed to create bus connection: %m");
goto finish;
}
r = analyze_time(bus);
r = analyze_blame(bus);
r = analyze_plot(bus);
else
}
pager_close();
return r < 0 ? EXIT_FAILURE : EXIT_SUCCESS;
}