Bug#594472: grub-pc: scary messages and very long boot time after upgrade

Tue Aug 31 18:29:05 UTC 2010

Below is the complete log of the boot with the kvm commandline i use,
deleted by aprroximately 5K Lines, as noted inline between ### ###.
Should the full log be of interest I am happy to send, just let me know.
This is a vanilla install with the current alpha-installer,
installation was as stated in my email before.

It appears that there are three udev timeouts; the first two after
30seconds each and the last one after 10 seconds, see below.

In addition, which I have just now spotted, is that on shutdown it
fails to bring down the LVM and the early crypto disks.

regards
   Darren

-----------------------------------------------------------------------------------------------------------------------------------
root at mypc:/root# kvm -hda /dev/mapper/m-luxm1 -hdb /dev/mapper/m-luxm2
-m 512 -serial stdio
Loading, please wait...
mdadm: /dev/md0 has been started with 2 drives.
mdadm: /dev/md1 has been started with 2 drives.
  Volume group "x" not found
  Skipping volume group x
Unable to find LVM volume x/root

udevadm settle - timeout of 30 seconds reached, the event queue contains:
  /sys/devices/virtual/block/md0 (1690)
  /sys/devices/virtual/block/md0 (1692)
  /sys/devices/virtual/block/md0 (1694)
  /sys/devices/virtual/block/md0 (1696)
  /sys/devices/virtual/block/md0 (1698)
  /sys/devices/virtual/block/md0 (1700)
  /sys/devices/virtual/block/md0 (1703)
  /sys/devices/virtual/block/md0 (1704)
  /sys/devices/virtual/block/md0 (1706)
  /sys/devices/virtual/block/md0 (1708)

### 1140 Lines deleted ###

  /sys/devices/virtual/block/md1 (2901)
  /sys/devices/virtual/block/md0 (2902)
  /sys/devices/virtual/block/md1 (2903)
  /sys/devices/virtual/block/md0 (2904)
  /sys/devices/virtual/block/md1 (2905)
  /sys/devices/virtual/block/md0 (2906)
  /sys/devices/virtual/block/md1 (2907)
  /sys/devices/virtual/block/md0 (2908)
  /sys/devices/virtual/block/md1 (2909)
  /sys/devices/virtual/block/md0 (2910)
  /sys/devices/virtual/block/md1 (2911)
  /sys/devices/virtual/block/md0 (2912)
Unlocking the disk /dev/disk/by-uuid/<diskID> (md1_crypt)
Enter passphrase:
  2 logical volume(s) in volume group "x" now active
cryptsetup: md1_crypt set up successfully

udevadm settle - timeout of 30 seconds reached, the event queue contains:
  /sys/devices/virtual/block/md0 (2894)
  /sys/devices/virtual/block/md0 (2896)
  /sys/devices/virtual/block/md0 (2898)
  /sys/devices/virtual/block/md0 (2900)
  /sys/devices/virtual/block/md0 (2902)
  /sys/devices/virtual/block/md0 (2904)
  /sys/devices/virtual/block/md0 (2906)
  /sys/devices/virtual/block/md0 (2908)

### 1950 Lines deleted ###

  /sys/devices/virtual/block/md0 (5118)
  /sys/devices/virtual/block/md1 (5119)
  /sys/devices/virtual/block/md0 (5120)
  /sys/devices/virtual/block/md1 (5121)
  /sys/devices/virtual/block/md0 (5122)
  /sys/devices/virtual/block/md1 (5123)

udevadm settle - timeout of 10 seconds reached, the event queue contains:
  /sys/devices/virtual/block/md0 (3314)
  /sys/devices/virtual/block/md0 (3316)
  /sys/devices/virtual/block/md0 (3318)
  /sys/devices/virtual/block/md0 (3320)
  /sys/devices/virtual/block/md0 (3322)
  /sys/devices/virtual/block/md0 (3324)
  /sys/devices/virtual/block/md0 (3326)
  /sys/devices/virtual/block/md0 (3328)

### 1980 Lines deleted ###

  /sys/devices/virtual/block/md1 (5563)
  /sys/devices/virtual/block/md0 (5564)
  /sys/devices/virtual/block/md1 (5565)
  /sys/devices/virtual/block/md0 (5566)
  /sys/devices/virtual/block/md1 (5567)
  /sys/devices/virtual/block/md0 (5568)
  /sys/devices/virtual/block/md1 (5569)
  /sys/devices/virtual/block/md0 (5570)
  /sys/devices/virtual/block/md1 (5571)
  /sys/devices/virtual/block/md0 (5572)
kinit: No resume image, doing normal boot...
INIT: version 2.88 booting
Using makefile-style concurrent boot in runlevel S.
Starting the hotplug events dispatcher: udevd.
Synthesizing the initial hotplug events...done.
Waiting for /dev to be fully populated...done.
Setting parameters of disc: (none).
Generating udev events for MD arrays...done.
Setting preliminary keymap...done.
Checking root file system...fsck from util-linux-ng 2.17.2
/dev/mapper/x-root: clean, 76211/579360 files, 795464/2316288 blocks
done.
Starting early crypto disks...done.
Cleaning up ifupdown....
Setting up networking....
Loading kernel modules...done.
Setting up LVM Volume Groups  Reading all physical volumes.  This may
take a while...
  Found volume group "x" using metadata type lvm2
  2 logical volume(s) in volume group "x" now active
.
Starting remaining crypto disks...done.
Activating lvm and md swap...done.
Checking file systems...fsck from util-linux-ng 2.17.2
/dev/md0: clean, 226/60240 files, 44737/240832 blocks
done.
Mounting local filesystems...done.
Activating swapfile swap...done.
Cleaning up temporary files....
Configuring network interfaces...done.
Setting kernel variables ...done.
Starting portmap daemon....
Starting NFS common utilities: statd.
Cleaning up temporary files....
Setting up ALSA...done (none loaded).
Setting console screen modes.

Skipping font and keymap setup (handled by console-setup).
Setting up console font and keymap...done.
Setting sensors limits.
Running scripts in rcS.d/ took 13 seconds.
INIT: Entering runlevel: 2
Using makefile-style concurrent boot in runlevel 2.
Starting NFS common utilities: statd.
Starting portmap daemon...Already running..
Starting enhanced syslogd: rsyslogd.
Starting ACPI services....
Starting system message bus: dbus.
Starting Hardware abstraction layer: hald.
Starting GNOME Display Manager: gdm.
Starting anac(h)ronistic cron: anacron.
Starting deferred execution scheduler: atd.
Starting Avahi mDNS/DNS-SD Daemon: avahi-daemon.
Starting periodic command scheduler: cron.
Starting Common Unix Printing System: cupsd.
Starting kerneloops:
Loading cpufreq kernel modules...done (none).
CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled,
governor not available...done.
Starting MD monitoring service: mdadm --monitor.
saned disabled; edit /etc/default/saned
Starting MTA: exim4.
Running scripts in rc2.d/ took 15 seconds.

INIT: Switching to runlevel: 0
INIT: Sending processes the TERM signal
Using makefile-style concurrent boot in runlevel 0.
Shutting down ALSA...done (none loaded).
Stopping GNOME Display Manager: gdm.
Stopping anac(h)ronistic cron: anacron.
Stopping deferred execution scheduler: atd.
Stopping kerneloops:
Stopping MD monitoring service: mdadm --monitor.
Stopping MTA: exim4_listener.
saned disabled; edit /etc/default/saned
Stopping Avahi mDNS/DNS-SD Daemon: avahi-daemon.
Asking all remaining processes to terminate...done.
All processes ended within 1 seconds....done.
Stopping enhanced syslogd: rsyslogd.
Stopping NFS common utilities: statd.
Stopping portmap daemon....
Deconfiguring network interfaces...Internet Systems Consortium DHCP
Client 4.1.1-P1
Copyright 2004-2010 Internet Systems Consortium.
All rights reserved.
For info, please visit https://www.isc.org/software/dhcp/

Listening on LPF/eth0/52:54:00:12:34:56
Sending on   LPF/eth0/52:54:00:12:34:56
Sending on   Socket/fallback
DHCPRELEASE on eth0 to 10.0.2.2 port 67
done.
Cleaning up ifupdown....
Saving the system clock.
Deactivating swap...done.
Unmounting local filesystems...done.
Stopping remaining crypto disks...done.
Shutting down LVM Volume Groups  Can't deactivate volume group "x"
with 1 open logical volume(s)
 failed!
Stopping early crypto disks...failed.
done.
Stopping MD array md0...done (stopped).
Will now halt.
[  298.393615] Power down.