Re: 7.0 slow multiuser boot

[mlelstv%serpens.de@localhost (Michael van Elst)]

frank%phoenix.owl.de@localhost (Frank Wille) writes:

>Hi!

>I did a comparison between a multiuser boot on NetBSD 6.1.5 and 7.0beta on
>a 68060/50MHz A4000. While /etc/rc finishes the multiuser startup with 6.1.5
>in one and a half minute, it needs nearly 9(!) minutes with 7.0!

>What happened there?

How often did you measure the startup time? The first time you start
the new sshd, it computes another hostkey which takes a very long time.

On a system that is about as fast, with a gcc-4.8-compiled but
otherwise pretty generic -current from December, I see the following
for the rc scripts, the kernel booting into single user before took
another ~30 seconds for a total startup time of about 4 minutes.

rc.conf only enables IPv6 autohost, ntpdate and sshd.

rcorder                    09:04:44
/etc/rc.d/wdogctl          09:04:46
/etc/rc.d/fsck_root        09:04:47
/etc/rc.d/root             09:04:48
/etc/rc.d/ccd              09:04:50
/etc/rc.d/rndctl           09:04:51
/etc/rc.d/cgd              09:04:52
/etc/rc.d/lvm              09:04:54
/etc/rc.d/raidframe        09:04:56
/etc/rc.d/DISKS            09:04:57
/etc/rc.d/swap1            09:04:58
/etc/rc.d/fsck             09:04:59
/etc/rc.d/mountcritlocal   09:05:01
/etc/rc.d/random_seed      09:05:04
/etc/rc.d/bootconf.sh      09:05:06
/etc/rc.d/ttys             09:05:08
/etc/rc.d/pf_boot          09:05:10
/etc/rc.d/ipsec            09:05:11
/etc/rc.d/ipfilter         09:05:12
/etc/rc.d/sysctl           09:05:13
/etc/rc.d/network          09:05:15
- that's mostly dhcpcd doing a rtsol equivalent

/etc/rc.d/wpa_supplicant   09:05:25
/etc/rc.d/dhclient         09:05:27
/etc/rc.d/dhcpcd           09:05:28
/etc/rc.d/ldpd             09:05:29
/etc/rc.d/npf              09:05:30
/etc/rc.d/pf               09:05:32
/etc/rc.d/route6d          09:05:33
/etc/rc.d/routed           09:05:34
/etc/rc.d/staticroute      09:05:36
/etc/rc.d/NETWORKING       09:05:37
/etc/rc.d/mountcritremote  09:05:38
/etc/rc.d/sysdb            09:05:40
/etc/rc.d/newsyslog        09:05:45
/etc/rc.d/wscons           09:05:46
/etc/rc.d/syslogd          09:05:48
/etc/rc.d/ppp              09:05:51
/etc/rc.d/named            09:05:52
/etc/rc.d/rtclocaltime     09:05:54
/etc/rc.d/ntpdate          09:05:55
- NTP steps time by 30 seconds, so that's just only
  9 seconds real time.

/etc/rc.d/rpcbind          09:06:34
/etc/rc.d/ypserv           09:06:35
/etc/rc.d/ypbind           09:06:37
/etc/rc.d/yppasswdd        09:06:38
/etc/rc.d/mountall         09:06:39
/etc/rc.d/perusertmp       09:06:42
/etc/rc.d/cleartmp         09:06:43
/etc/rc.d/xfs              09:06:45
/etc/rc.d/accounting       09:06:46
/etc/rc.d/amd              09:06:47
/etc/rc.d/dmesg            09:06:49
/etc/rc.d/fccache          09:06:51
/etc/rc.d/veriexec         09:06:56
/etc/rc.d/ldconfig         09:06:58
/etc/rc.d/quota            09:07:02
/etc/rc.d/mountd           09:07:04
/etc/rc.d/nfsd             09:07:06
/etc/rc.d/nfslocking       09:07:07
/etc/rc.d/pwcheck          09:07:09
/etc/rc.d/gpio             09:07:10
/etc/rc.d/ipnat            09:07:11
/etc/rc.d/securelevel      09:07:13
/etc/rc.d/swap2            09:07:15
/etc/rc.d/virecover        09:07:16
/etc/rc.d/altqd            09:07:19
/etc/rc.d/ifwatchd         09:07:20
/etc/rc.d/isdnd            09:07:21
/etc/rc.d/ipfs             09:07:22
/etc/rc.d/ipmon            09:07:23
/etc/rc.d/kdc              09:07:25
/etc/rc.d/pflogd           09:07:26
/etc/rc.d/racoon           09:07:27
/etc/rc.d/savecore         09:07:29
/etc/rc.d/SERVERS          09:07:30
/etc/rc.d/DAEMON           09:07:32
/etc/rc.d/apmd             09:07:33
/etc/rc.d/bluetooth        09:07:34
/etc/rc.d/bootparams       09:07:36
/etc/rc.d/devpubd          09:07:37
/etc/rc.d/dhcpd            09:07:38
/etc/rc.d/dhcrelay         09:07:39
/etc/rc.d/ftp_proxy        09:07:41
/etc/rc.d/irdaattach       09:07:42
/etc/rc.d/local            09:07:43
/etc/rc.d/lpd              09:07:45
/etc/rc.d/makemandb        09:07:46
/etc/rc.d/mopd             09:07:47
/etc/rc.d/motd             09:07:49
/etc/rc.d/mrouted          09:07:50
/etc/rc.d/ntpd             09:07:52
/etc/rc.d/powerd           09:07:53
/etc/rc.d/rarpd            09:07:54
/etc/rc.d/rbootd           09:07:56
/etc/rc.d/rtadvd           09:07:57
/etc/rc.d/rwho             09:07:58
/etc/rc.d/screenblank      09:07:59
/etc/rc.d/timed            09:08:01
/etc/rc.d/tpctl            09:08:02
/etc/rc.d/LOGIN            09:08:03
/etc/rc.d/xdm              09:08:04
/etc/rc.d/wsmoused         09:08:06
/etc/rc.d/sshd             09:08:07
- part of the activity continues in the background

/etc/rc.d/raidframeparity  09:08:14
/etc/rc.d/postfix          09:08:15
- postfix logs that it starts the daemon at 09:08:51
  so that is probably the startup script slowed down by
  concurrent sshd startup.

/etc/rc.d/ndbootd          09:08:53
/etc/rc.d/moused           09:08:55
/etc/rc.d/mixerctl         09:08:56
/etc/rc.d/mdnsd            09:08:58
/etc/rc.d/isibootd         09:08:59
/etc/rc.d/iscsi_target     09:09:00
/etc/rc.d/inetd            09:09:02
/etc/rc.d/identd           09:09:03
/etc/rc.d/httpd            09:09:04
/etc/rc.d/hostapd          09:09:06
/etc/rc.d/ftpd             09:09:07
/etc/rc.d/envsys           09:09:08
/etc/rc.d/cron             09:09:09
finished                   09:09:11

-- 
-- 
                                Michael van Elst
Internet: mlelstv%serpens.de@localhost
                                "A potential Snark may lurk in every tree."