systemd-analyze.in revision 2f4da3e6c298bc0ae6243cae47aedb01c50fa870
#!@PYTHON_BINARY@
import sys, os
import argparse
from gi.repository import Gio
try:
import cairo
except ImportError:
cairo = None
def acquire_time_data():
manager = Gio.DBusProxy.new_for_bus_sync(bus, Gio.DBusProxyFlags.NONE,
None, 'org.freedesktop.systemd1', '/org/freedesktop/systemd1', 'org.freedesktop.systemd1.Manager', None)
units = manager.ListUnits()
l = []
for i in units:
if i[5] != "":
continue
properties = Gio.DBusProxy.new_for_bus_sync(bus, Gio.DBusProxyFlags.NONE,
None, 'org.freedesktop.systemd1', i[6], 'org.freedesktop.DBus.Properties', None)
ixt = properties.Get('(ss)', 'org.freedesktop.systemd1.Unit', 'InactiveExitTimestampMonotonic')
aet = properties.Get('(ss)', 'org.freedesktop.systemd1.Unit', 'ActiveEnterTimestampMonotonic')
axt = properties.Get('(ss)', 'org.freedesktop.systemd1.Unit', 'ActiveExitTimestampMonotonic')
iet = properties.Get('(ss)', 'org.freedesktop.systemd1.Unit', 'InactiveEnterTimestampMonotonic')
l.append((str(i[0]), ixt, aet, axt, iet))
return l
def acquire_start_time():
properties = Gio.DBusProxy.new_for_bus_sync(bus, Gio.DBusProxyFlags.NONE,
None, 'org.freedesktop.systemd1', '/org/freedesktop/systemd1', 'org.freedesktop.DBus.Properties', None)
# Note that the firmware/loader times are returned as positive
# values but are actually considered negative from the point
# in time of kernel initialization. Also, the monotonic kernel
# time will always be 0 since that's the epoch of the
# monotonic clock. Since we want to know whether the kernel
# timestamp is set at all we will instead ask for the realtime
# clock for this timestamp.
firmware_time = properties.Get('(ss)', 'org.freedesktop.systemd1.Manager', 'FirmwareTimestampMonotonic')
loader_time = properties.Get('(ss)', 'org.freedesktop.systemd1.Manager', 'LoaderTimestampMonotonic')
kernel_time = properties.Get('(ss)', 'org.freedesktop.systemd1.Manager', 'KernelTimestamp')
initrd_time = properties.Get('(ss)', 'org.freedesktop.systemd1.Manager', 'InitRDTimestampMonotonic')
userspace_time = properties.Get('(ss)', 'org.freedesktop.systemd1.Manager', 'UserspaceTimestampMonotonic')
finish_time = properties.Get('(ss)', 'org.freedesktop.systemd1.Manager', 'FinishTimestampMonotonic')
if finish_time == 0:
sys.exit("Bootup is not yet finished. Please try again later.")
assert firmware_time >= loader_time
assert initrd_time <= userspace_time
assert userspace_time <= finish_time
return firmware_time, loader_time, kernel_time, initrd_time, userspace_time, finish_time
def draw_box(context, j, k, l, m, r = 0, g = 0, b = 0):
context.save()
context.set_source_rgb(r, g, b)
context.rectangle(j, k, l, m)
context.fill()
context.restore()
def draw_text(context, x, y, text, size = 12, r = 0, g = 0, b = 0, vcenter = 0.5, hcenter = 0.5):
context.save()
context.set_source_rgb(r, g, b)
context.select_font_face("Sans", cairo.FONT_SLANT_NORMAL, cairo.FONT_WEIGHT_NORMAL)
context.set_font_size(size)
if vcenter or hcenter:
x_bearing, y_bearing, width, height = context.text_extents(text)[:4]
if hcenter:
x = x - width*hcenter - x_bearing
if vcenter:
y = y - height*vcenter - y_bearing
context.move_to(x, y)
context.show_text(text)
context.restore()
def time():
firmware_time, loader_time, kernel_time, initrd_time, userspace_time, finish_time = acquire_start_time()
sys.stdout.write("Startup finished in ")
if firmware_time > 0:
sys.stdout.write("%lums (firmware) + " % ((firmware_time - loader_time) / 1000))
if loader_time > 0:
sys.stdout.write("%lums (loader) + " % (loader_time / 1000))
if initrd_time > 0:
sys.stdout.write("%lums (kernel) + %lums (initrd) + " % (initrd_time / 1000, (userspace_time - initrd_time) / 1000))
elif kernel_time > 0:
sys.stdout.write("%lums (kernel) + " % (userspace_time / 1000))
sys.stdout.write("%lums (userspace) " % ((finish_time - userspace_time) / 1000))
if kernel_time > 0:
sys.stdout.write("= %lums\n" % ((firmware_time + finish_time) / 1000))
else:
sys.stdout.write("= %lums\n" % ((finish_time - userspace_time) / 1000))
def blame():
data = acquire_time_data()
s = sorted(data, key = lambda i: i[2] - i[1], reverse = True)
for name, ixt, aet, axt, iet in s:
if ixt <= 0 or aet <= 0:
continue
if aet <= ixt:
continue
sys.stdout.write("%6lums %s\n" % ((aet - ixt) / 1000, name))
def plot():
if cairo is None:
sys.exit("Failed to initilize python-cairo required for 'plot' verb.")
firmware_time, loader_time, kernel_time, initrd_time, userspace_time, finish_time = acquire_start_time()
data = acquire_time_data()
s = sorted(data, key = lambda i: i[1])
# Account for kernel and initramfs bars if they exist
if initrd_time > 0:
count = 3
else:
count = 2
for name, ixt, aet, axt, iet in s:
if (ixt >= userspace_time and ixt <= finish_time) or \
(aet >= userspace_time and aet <= finish_time) or \
(axt >= userspace_time and axt <= finish_time):
count += 1
border = 100
bar_height = 20
bar_space = bar_height * 0.1
# 1000px = 10s, 1px = 10ms
width = finish_time/10000 + border*2
height = count * (bar_height + bar_space) + border * 2
if width < 1000:
width = 1000
surface = cairo.SVGSurface(sys.stdout, width, height)
context = cairo.Context(surface)
draw_box(context, 0, 0, width, height, 1, 1, 1)
context.translate(border + 0.5, border + 0.5)
context.save()
context.set_line_width(1)
context.set_source_rgb(0.7, 0.7, 0.7)
for x in range(0, int(finish_time/10000) + 100, 100):
context.move_to(x, 0)
context.line_to(x, height-border*2)
context.move_to(0, 0)
context.line_to(width-border*2, 0)
context.move_to(0, height-border*2)
context.line_to(width-border*2, height-border*2)
context.stroke()
context.restore()
osrel = "Linux"
if os.path.exists("/etc/os-release"):
for line in open("/etc/os-release"):
if line.startswith('PRETTY_NAME='):
osrel = line[12:]
osrel = osrel.strip('\"\n')
break
banner = "{} {} ({} {}) {}".format(osrel, *(os.uname()[1:5]))
draw_text(context, 0, -15, banner, hcenter = 0, vcenter = 1)
for x in range(0, int(finish_time/10000) + 100, 100):
draw_text(context, x, -5, "%lus" % (x/100), vcenter = 0, hcenter = 0)
y = 0
# draw boxes for kernel and initramfs boot time
if initrd_time > 0:
draw_box(context, 0, y, initrd_time/10000, bar_height, 0.7, 0.7, 0.7)
draw_text(context, 10, y + bar_height/2, "kernel", hcenter = 0)
y += bar_height + bar_space
draw_box(context, initrd_time/10000, y, userspace_time/10000-initrd_time/10000, bar_height, 0.7, 0.7, 0.7)
draw_text(context, initrd_time/10000 + 10, y + bar_height/2, "initramfs", hcenter = 0)
y += bar_height + bar_space
else:
draw_box(context, 0, y, userspace_time/10000, bar_height, 0.6, 0.6, 0.6)
draw_text(context, 10, y + bar_height/2, "kernel", hcenter = 0)
y += bar_height + bar_space
draw_box(context, userspace_time/10000, y, finish_time/10000-userspace_time/10000, bar_height, 0.7, 0.7, 0.7)
draw_text(context, userspace_time/10000 + 10, y + bar_height/2, "userspace", hcenter = 0)
y += bar_height + bar_space
for name, ixt, aet, axt, iet in s:
drawn = False
left = -1
if ixt >= userspace_time and ixt <= finish_time:
# Activating
a = ixt
b = min(filter(lambda x: x >= ixt, (aet, axt, iet, finish_time))) - ixt
draw_box(context, a/10000, y, b/10000, bar_height, 1, 0, 0)
drawn = True
if left < 0:
left = a
if aet >= userspace_time and aet <= finish_time:
# Active
a = aet
b = min(filter(lambda x: x >= aet, (axt, iet, finish_time))) - aet
draw_box(context, a/10000, y, b/10000, bar_height, .8, .6, .6)
drawn = True
if left < 0:
left = a
if axt >= userspace_time and axt <= finish_time:
# Deactivating
a = axt
b = min(filter(lambda x: x >= axt, (iet, finish_time))) - axt
draw_box(context, a/10000, y, b/10000, bar_height, .6, .4, .4)
drawn = True
if left < 0:
left = a
if drawn:
x = left/10000
if x < width/2-border:
draw_text(context, x + 10, y + bar_height/2, name, hcenter = 0)
else:
draw_text(context, x - 10, y + bar_height/2, name, hcenter = 1)
y += bar_height + bar_space
draw_text(context, 0, height-border*2, "Legend: Red = Activating; Pink = Active; Dark Pink = Deactivating", hcenter = 0, vcenter = -1)
if initrd_time > 0:
draw_text(context, 0, height-border*2 + bar_height, "Startup finished in %lums (kernel) + %lums (initramfs) + %lums (userspace) = %lums" % ( \
initrd_time/1000, \
(userspace_time - initrd_time)/1000, \
(finish_time - userspace_time)/1000, \
finish_time/1000), hcenter = 0, vcenter = -1)
else:
draw_text(context, 0, height-border*2 + bar_height, "Startup finished in %lums (kernel) + %lums (userspace) = %lums" % ( \
userspace_time/1000, \
(finish_time - userspace_time)/1000, \
finish_time/1000), hcenter = 0, vcenter = -1)
surface.finish()
parser = argparse.ArgumentParser(formatter_class=argparse.RawDescriptionHelpFormatter,
version='systemd-analyze @PACKAGE_VERSION@',
description='Process systemd profiling information',
epilog='''\
time - print time spent in the kernel before reaching userspace
blame - print list of running units ordered by time to init
plot - output SVG graphic showing service initialization
''')
parser.add_argument('action', choices=('time', 'blame', 'plot'),
default='time', nargs='?',
help='action to perform (default: time)')
parser.add_argument('--user', action='store_true',
help='use the session bus')
args = parser.parse_args()
if args.user:
bus = Gio.BusType.SESSION
else:
bus = Gio.BusType.SYSTEM
verb = {'time' : time,
'blame': blame,
'plot' : plot,
}
verb.get(args.action)()