> From: Bruce Dubbs <bruce.du...@gmail.com> >>> Do you have any data? How fast can you get from grub to the login
>> LFS 7.2 from grub to login prompt and back with 2.4 Ghz (dual) >> Core i5 on the notebook running in Parallels 8, no SSD: >> >> SysVinit: 15 sec. bootup, 14 sec. shutdown >> Systemd : 4.5 sec. bootup, 4 sec. shutdown > For the LFS system, can you post the output from a typical boot in > /var/log/boot.log. For instance, mine is: > > Oct 02 20:19:22 +00:00 (none) Mounting virtual file systems: /run /proc /sys > Oct 02 20:19:25 +00:00 blfs Retrying failed uevents, if any... OK > Oct 02 20:19:31 +00:00 blfs Starting system log daemon... OK > Oct 02 20:19:31 +00:00 blfs Starting SSH Server... OK > > In this case, there seems to be a longer than usual wait in udev_retry. Mine usually spends 5 seconds there, too, a large part of the boot process. Now I also wonder, why does the shutdown take so long in sysvinit? Here is a SysVinit sample taking 13 secs. in the log, plus about a second when timing it from the grub screen: Sep 25 02:40:06 +08:00 (none) Mounting virtual file systems: /run /proc /sys /dev OK Sep 25 02:40:06 +08:00 (none) Bringing up the loopback interface... OK Sep 25 02:40:06 +08:00 (none) Setting hostname to zoelfs... OK Sep 25 02:40:06 +08:00 zoelfs Populating /dev with device nodes... OK Sep 25 02:40:12 +08:00 zoelfs Activating all swap files/partitions... OK Sep 25 02:40:12 +08:00 zoelfs Mounting root file system in read-only mode... OK Sep 25 02:40:12 +08:00 zoelfs Checking file systems... OK Sep 25 02:40:13 +08:00 zoelfs Remounting root file system in read-write mode... OK Sep 25 02:40:13 +08:00 zoelfs Recording existing mounts in /etc/mtab... OK Sep 25 02:40:13 +08:00 zoelfs Mounting remaining file systems... OK Sep 25 02:40:13 +08:00 zoelfs Cleaning file systems: /tmp OK Sep 25 02:40:13 +08:00 zoelfs Retrying failed uevents, if any... OK Sep 25 02:40:18 +08:00 zoelfs Starting system log daemon... OK Sep 25 02:40:18 +08:00 zoelfs Starting kernel log daemon... OK Sep 25 02:40:19 +08:00 zoelfs Bringing up the eth0 interface... Sep 25 02:40:19 +08:00 zoelfs Adding IPv4 address 10.211.55.101 to the eth0 interface... OK Sep 25 02:40:19 +08:00 zoelfs Setting up default gateway... OK Sep 25 02:40:19 +08:00 zoelfs Initializing kernel random number generator... OK Sep 25 02:40:19 +08:00 zoelfs Starting SSH Server... OK Here is a sample from Systemd with the same setup, taking about 3 sec according to the log plus about 1 sec. when timing it from the grub screen: # journalctl -b (with log level info, without most of the kernel msg) Logs end at Thu, 04 Oct 2012 12:47:39 +0800. 12:47:33 systemd-journal[888]: Journal started 12:47:33 systemd-vconsole-setup[884]: sh: gzip: command not found 12:47:33 systemd-fsck[879]: Linux: clean, 86348/288000 files, 549180/1152000 blocks 12:47:33 kernel[1324]: klogd 1.5.0, log source = /proc/kmsg started. 12:47:33 kernel[1324]: [ 0.000000] Initializing cgroup subsys cpuset 12:47:33 kernel[1324]: [ 2.034029] systemd-system- (874) used greatest stack depth: 5772 bytes left 12:47:33 systemd-logind[1310]: Watching system buttons on /dev/input/event0 (Power Button) 12:47:33 systemd-logind[1310]: Watching system buttons on /dev/input/event1 (Sleep Button) 12:47:33 systemd-logind[1310]: New seat seat0. 12:47:33 systemd[1]: Startup finished in 1s 927ms 101us (kernel) + 973ms 490us (userspace) = 2s 900ms 591us. Chris -- below with some more details on what systemd is actually starting: root [ ~ ]# journalctl -b (with log level debug, some of the more interesting lines) Logs end at Thu, 04 Oct 2012 13:19:17 +0800. 13:19:16 systemd-journal[888]: Journal started 13:19:16 systemd[1]: Successfully opened journal for logging. 13:19:16 systemd[1]: Job systemd-vconsole-setup.service/start finished, result=done 13:19:16 systemd[1]: Accepted connection on private bus. 13:19:16 systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/system 13:19:16 systemd[1]: systemd-vconsole-setup.service: cgroup is empty 13:19:16 systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/L 13:19:16 systemd[1]: dev-ttyS0.device changed dead -> plugged 13:19:16 systemd[1]: sys-devices-platform-serial8250-tty-ttyS0.device changed dead -> plugged 13:19:16 systemd[1]: dev-ttyS1.device changed dead -> plugged 13:19:16 systemd[1]: sys-devices-pci0000:00-0000:00:06.0-net-eth1.device changed dead -> plugged 13:19:16 systemd[1]: sys-devices-pci0000:00-0000:00:05.0-net-eth0.device changed dead -> plugged 13:19:16 systemd[1]: sys-devices-pci0000:00-0000:00:07.0-net-eth2.device changed dead -> plugged 13:19:16 systemd-fsck[879]: Linux: clean, 86348/288000 files, 549268/1152000 blocks 13:19:16 systemd[1]: Child 879 belongs to systemd-fsck-root.service 13:19:16 systemd[1]: systemd-fsck-root.service: main process exited, code=exited, status=0 13:19:16 systemd[1]: systemd-fsck-root.service changed start -> dead 13:19:16 systemd[1]: Job systemd-fsck-root.service/start finished, result=done 13:19:16 systemd[1]: About to execute: /usr/lib/systemd/systemd-remount-fs 13:19:16 systemd[1]: Forked /usr/lib/systemd/systemd-remount-fs as 1420 13:19:16 systemd[1]: systemd-remount-fs.service changed dead -> start 13:19:16 systemd[1]: Accepted connection on private bus. 13:19:16 systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/system 13:19:16 systemd[1]: systemd-fsck-root.service: cgroup is empty 13:19:16 systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/L 13:19:16 systemd[1]: dev-disk-by\x2dlabel-Finnix\x5cx20104.device changed dead -> plugged 13:19:16 systemd[1]: dev-disk-by\x2did-ata\x2dVirtual_DVD\x2dROM__1__\x2d_31415B265.device changed dead -> p 13:19:16 systemd[1]: dev-cdrom.device changed dead -> plugged 13:19:16 systemd[1]: dev-sr0.device changed dead -> plugged 13:19:16 systemd[1]: sys-devices-pci0000:00-0000:00:1f.1-ata1-host0-target0:0:1-0:0:1:0-block-sr0.device cha 13:19:16 systemd[1]: Running GC... 13:19:16 systemd[1]: sys-devices-virtual-net-sit0.device changed dead -> plugged 13:19:16 systemd[1]: dev-disk-by\x2did-ata\x2dZOE_LFS_7.2\x2d0_436EWKFFD284SFF7KJ4Z.device changed dead -> p 13:19:16 systemd[1]: dev-sda.device changed dead -> plugged 13:19:16 systemd[1]: sys-devices-pci0000:00-0000:00:1f.1-ata1-host0-target0:0:0-0:0:0:0-block-sda.device cha 13:19:16 systemd[1]: dev-disk-by\x2duuid-7fd5096e\x2df205\x2d45f4\x2dabf4\x2d5ec396c29b36.device changed dea 13:19:16 systemd[1]: dev-disk-by\x2dlabel-Swap.device changed dead -> plugged 13:19:16 systemd[1]: dev-disk-by\x2did-ata\x2dZOE_LFS_7.2\x2d0_436EWKFFD284SFF7KJ4Z\x2dpart2.device changed 13:19:16 systemd[1]: dev-sda2.device changed dead -> plugged 13:19:16 systemd[1]: Job dev-sda2.device/start finished, result=done 13:19:16 systemd[1]: sys-devices-pci0000:00-0000:00:1f.1-ata1-host0-target0:0:0-0:0:0:0-block-sda-sda2.devic 13:19:16 systemd[1]: About to execute: /sbin/swapon -p 1 /dev/sda2 13:19:16 systemd[1]: Forked /sbin/swapon as 1430 13:19:16 systemd[1]: dev-sda2.swap changed dead -> activating 13:19:16 systemd[1]: systemd-remount-fs.service changed start -> exited 13:19:16 systemd[1]: Job systemd-remount-fs.service/start finished, result=done 13:19:16 systemd[1]: local-fs-pre.target changed dead -> active 13:19:16 systemd[1]: Job local-fs-pre.target/start finished, result=done 13:19:16 systemd[1]: About to execute: /bin/mount tmpfs /tmp -t tmpfs -o mode=1777,strictatime 13:19:16 systemd[1]: Forked /bin/mount as 1432 13:19:16 systemd[1]: tmp.mount changed dead -> mounting 13:19:16 systemd[1]: systemd-random-seed-load.service changed dead -> start 13:19:16 systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/system 13:19:16 systemd[1]: dev-disk-by\x2duuid-7fd5096e\x2df205\x2d45f4\x2dabf4\x2d5ec396c29b36.swap changed dead 13:19:16 systemd[1]: dev-disk-by\x2dlabel-Swap.swap changed dead -> active 13:19:16 systemd[1]: dev-disk-by\x2did-ata\x2dZOE_LFS_7.2\x2d0_436EWKFFD284SFF7KJ4Z\x2dpart2.swap changed de 13:19:16 systemd[1]: Child 1430 belongs to dev-sda2.swap 13:19:16 systemd[1]: Job dev-sda2.swap/start finished, result=done 13:19:16 systemd[1]: swap.target changed dead -> active 13:19:16 systemd[1]: dev-disk-by\x2dlabel-Linux.device changed dead -> plugged 13:19:16 systemd[1]: dbus.socket changed dead -> listening 13:19:16 systemd[1]: Job dbus.socket/start finished, result=done 13:19:16 systemd[1]: sockets.target changed dead -> active 13:19:16 systemd[1]: Job sockets.target/start finished, result=done 13:19:16 systemd[1]: systemd-tmpfiles-clean.timer changed dead -> waiting 13:19:16 systemd[1]: About to execute: /usr/lib/systemd/systemd-user-sessions start 13:19:16 systemd[1]: About to execute: /sbin/agetty --noclear tty1 38400 13:19:16 systemd[1]: Received SIGCHLD from PID 1441 (ip). 13:19:16 systemd[1]: Got SIGCHLD for process 1441 (ip) 13:19:16 systemd[1]: Child 1441 died (code=exited, status=0/SUCCESS) 13:19:16 kernel[1452]: klogd 1.5.0, log source = /proc/kmsg started. 13:19:16 systemd[1]: Received name :1.0 in reply to Hello 13:19:16 systemd[1]: Successfully connected to system D-Bus bus afb9d878d8adc3c90bb57927506d1c54 as :1.0 13:19:16 systemd[1]: Successfully initialized API on the system bus 13:19:16 systemd[1]: Got D-Bus request: org.freedesktop.DBus.NameOwnerChanged() on /org/freedesktop/DBus 13:19:16 systemd[1]: systemd-logind.service's D-Bus name org.freedesktop.login1 now registered by :1.1 13:19:16 systemd[1]: systemd-logind.service changed start -> running 13:19:16 systemd[1]: Job systemd-logind.service/start finished, result=done 13:19:17 systemd[1]: Incoming traffic on sshd.socket 13:19:17 systemd[1]: Enqueued job sshd@0-10.211.55.125:22-10.211.55.2:51597.service/start as 61 13:19:17 systemd[1]: About to execute: /bin/mkdir -p /var/run/sshd 13:19:17 systemd[1]: Forked /bin/mkdir as 1465 13:19:17 systemd[1]: sshd@0-10.211.55.125:22-10.211.55.2:51597.service changed dead -> start-pre 13:19:17 systemd[1]: Child 1465 belongs to sshd@0-10.211.55.125:22-10.211.55.2:51597.service 13:19:17 systemd[1]: sshd@0-10.211.55.125:22-10.211.55.2:51597.service: control process exited, code=exited 13:19:17 systemd[1]: About to execute: /usr/sbin/sshd -i 13:19:17 systemd[1]: Forked /usr/sbin/sshd as 1467 -- http://linuxfromscratch.org/mailman/listinfo/lfs-dev FAQ: http://www.linuxfromscratch.org/faq/ Unsubscribe: See the above information page
