Kernel takes a 6 minutes break and go outside take a cup of coffee

Binary package hint: linux-image-2.6.17-10-generic

The 2.6.17-10 kernel (either 386 or generic version) both stop during boot sequence doing apparently nothing for about 6 minutes. The live cd does not show this behaviour, only the installed version. Dapper was installed with exact same hardware configuration and disk layout but do not show this behaviour. This happen if i do a dapper -> edgy upgrade but also if a do a full edgy installation. There is no error message on screen, neither in dmesg. To common user (boot with grub in quiet mode), the behaviour is just a black screen with a blinking cursor. This can lead user to think ubuntu is unusable. If you disable splash and quiet mode, you get some kernel message until a point where it loads usb drivers for my logitech wireless mouse. At that moment it stops 6 minutes and after it starts loading raid arrays.

This bug is very painful for booting the computer.

Here is complete dmesg output, with a marker at the moment it took a Six minutes pause.

[17179569.184000] Linux version 2.6.17-10-generic (root@vernadsky) (gcc version 4.1.2 20060928 (prerelease) (Ubuntu 4.1.1-13ubuntu5)) #2 SMP Fri Oct 13 18:45:35 UTC 2006 (Ubuntu 2.6.17-10.33-generic)
[17179569.184000] BIOS-provided physical RAM map:
[17179569.184000] BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
[17179569.184000] BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
[17179569.184000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
[17179569.184000] BIOS-e820: 0000000000100000 - 000000003fff0000 (usable)
[17179569.184000] BIOS-e820: 000000003fff0000 - 000000003fff3000 (ACPI NVS)
[17179569.184000] BIOS-e820: 000000003fff3000 - 0000000040000000 (ACPI data)
[17179569.184000] BIOS-e820: 00000000fec00000 - 00000000fec01000 (reserved)
[17179569.184000] BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
[17179569.184000] BIOS-e820: 00000000ffff0000 - 0000000100000000 (reserved)
[17179569.184000] 127MB HIGHMEM available.
[17179569.184000] 896MB LOWMEM available.
[17179569.184000] On node 0 totalpages: 262128
[17179569.184000] DMA zone: 4096 pages, LIFO batch:0
[17179569.184000] Normal zone: 225280 pages, LIFO batch:31
[17179569.184000] HighMem zone: 32752 pages, LIFO batch:7
[17179569.184000] DMI 2.2 present.
[17179569.184000] ACPI: RSDP (v000 Nvidia ) @ 0x000f6b90
[17179569.184000] ACPI: RSDT (v001 Nvidia AWRDACPI 0x42302e31 AWRD 0x00000000) @ 0x3fff3000
[17179569.184000] ACPI: FADT (v001 Nvidia AWRDACPI 0x42302e31 AWRD 0x00000000) @ 0x3fff3040
[17179569.184000] ACPI: MADT (v001 Nvidia AWRDACPI 0x42302e31 AWRD 0x00000000) @ 0x3fff79c0
[17179569.184000] ACPI: DSDT (v001 NVIDIA AWRDACPI 0x00001000 MSFT 0x0100000d) @ 0x00000000
[17179569.184000] ACPI: PM-Timer IO Port: 0x4008
[17179569.184000] ACPI: Local APIC address 0xfee00000
[17179569.184000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[17179569.184000] Processor #0 6:8 APIC version 16
[17179569.184000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
[17179569.184000] ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0])
[17179569.184000] IOAPIC[0]: apic_id 2, version 17, address 0xfec00000, GSI 0-23
[17179569.184000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[17179569.184000] ACPI: BIOS IRQ0 pin2 override ignored.
[17179569.184000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[17179569.184000] ACPI: IRQ9 used by override.
[17179569.184000] Enabling APIC mode: Flat. Using 1 I/O APICs
[17179569.184000] Using ACPI (MADT) for SMP configuration information
[17179569.184000] Allocating PCI resources starting at 50000000 (gap: 40000000:bec00000)
[17179569.184000] Built 1 zonelists
[17179569.184000] Kernel command line: root=/dev/md3 ro
[17179569.184000] mapped APIC to ffffd000 (fee00000)
[17179569.184000] mapped IOAPIC to ffffc000 (fec00000)
[17179569.184000] Enabling fast FPU save and restore... done.
[17179569.184000] Enabling unmasked SIMD FPU exception support... done.
[17179569.184000] Initializing CPU#0
[17179569.184000] PID hash table entries: 4096 (order: 12, 16384 bytes)
[17179569.184000] Detected 2004.856 MHz processor.
[17179569.184000] Using pmtmr for high-res timesource
[17179569.184000] Console: colour VGA+ 80x25
[17179570.116000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[17179570.116000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[17179570.144000] Memory: 1028996k/1048512k available (1910k kernel code, 18832k reserved, 1070k data, 308k init, 131008k highmem)
[17179570.144000] Checking if this processor honours the WP bit even in supervisor mode... Ok.
[17179570.224000] Calibrating delay using timer specific routine.. 4013.97 BogoMIPS (lpj=8027942)
[17179570.224000] Security Framework v1.0.0 initialized
[17179570.224000] SELinux: Disabled at boot.
[17179570.224000] Mount-cache hash table entries: 512
[17179570.224000] CPU: After generic identify, caps: 0383fbff c1c3fbff 00000000 00000000 00000000 00000000 00000000
[17179570.224000] CPU: After vendor identify, caps: 0383fbff c1c3fbff 00000000 00000000 00000000 00000000 00000000
[17179570.224000] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
[17179570.224000] CPU: L2 Cache: 256K (64 bytes/line)
[17179570.224000] CPU: After all inits, caps: 0383fbff c1c3fbff 00000000 00000420 00000000 00000000 00000000
[17179570.224000] Checking 'hlt' instruction... OK.
[17179570.240000] SMP alternatives: switching to UP code
[17179570.240000] Freeing SMP alternatives: 16k freed
[17179570.240000] checking if image is initramfs... it is
[17179570.740000] Freeing initrd memory: 6060k freed
[17179570.740000] ACPI: Core revision 20060707
[17179570.748000] ACPI: Looking for DSDT ... not found!
[17179570.760000] CPU0: AMD Athlon(tm) XP 2400+ stepping 01
[17179570.760000] Total of 1 processors activated (4013.97 BogoMIPS).
[17179570.760000] ENABLING IO-APIC IRQs
[17179570.760000] ..TIMER: vector=0x31 apic1=0 pin1=0 apic2=-1 pin2=-1
[17179570.904000] Brought up 1 CPUs
[17179570.904000] migration_cost=0
[17179570.904000] NET: Registered protocol family 16
[17179570.904000] EISA bus registered
[17179570.904000] ACPI: bus type pci registered
[17179570.904000] PCI: PCI BIOS revision 2.10 entry at 0xfb420, last bus=2
[17179570.904000] PCI: Using configuration type 1
[17179570.904000] Setting up standard PCI resources
[17179570.912000] ACPI: Interpreter enabled
[17179570.912000] ACPI: Using IOAPIC for interrupt routing
[17179570.912000] ACPI: PCI Root Bridge [PCI0] (0000:00)
[17179570.912000] PCI: Probing PCI hardware (bus 00)
[17179570.916000] PCI: nForce2 C1 Halt Disconnect fixup
[17179570.916000] PCI: Ignoring BAR0-3 of IDE controller 0000:00:09.0
[17179570.920000] Boot video device is 0000:02:00.0
[17179570.920000] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[17179570.984000] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.HUB0._PRT]
[17179570.984000] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.AGPB._PRT]
[17179570.984000] ACPI: PCI Interrupt Link [LNK1] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
[17179570.984000] ACPI: PCI Interrupt Link [LNK2] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
[17179570.988000] ACPI: PCI Interrupt Link [LNK3] (IRQs 3 4 5 6 7 10 *11 12 14 15)
[17179570.988000] ACPI: PCI Interrupt Link [LNK4] (IRQs 3 4 5 6 7 10 11 *12 14 15)
[17179570.988000] ACPI: PCI Interrupt Link [LNK5] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
[17179570.988000] ACPI: PCI Interrupt Link [LUBA] (IRQs 3 4 5 6 7 10 *11 12 14 15)
[17179570.988000] ACPI: PCI Interrupt Link [LUBB] (IRQs 3 4 *5 6 7 10 11 12 14 15)
[17179570.992000] ACPI: PCI Interrupt Link [LMAC] (IRQs 3 4 5 6 7 *10 11 12 14 15)
[17179570.992000] ACPI: PCI Interrupt Link [LAPU] (IRQs 3 4 5 6 7 10 *11 12 14 15)
[17179570.992000] ACPI: PCI Interrupt Link [LACI] (IRQs 3 4 *5 6 7 10 11 12 14 15)
[17179570.992000] ACPI: PCI Interrupt Link [LMCI] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
[17179570.992000] ACPI: PCI Interrupt Link [LSMB] (IRQs 3 4 5 6 7 *10 11 12 14 15)
[17179570.996000] ACPI: PCI Interrupt Link [LUB2] (IRQs 3 4 5 6 7 10 11 *12 14 15)
[17179570.996000] ACPI: PCI Interrupt Link [LFIR] (IRQs 3 4 5 6 7 *10 11 12 14 15)
[17179570.996000] ACPI: PCI Interrupt Link [L3CM] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
[17179570.996000] ACPI: PCI Interrupt Link [LIDE] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
[17179571.000000] ACPI: PCI Interrupt Link [APC1] (IRQs *16), disabled.
[17179571.000000] ACPI: PCI Interrupt Link [APC2] (IRQs *17), disabled.
[17179571.000000] ACPI: PCI Interrupt Link [APC3] (IRQs *18), disabled.
[17179571.000000] ACPI: PCI Interrupt Link [APC4] (IRQs *19), disabled.
[17179571.000000] ACPI: PCI Interrupt Link [APCE] (IRQs *16), disabled.
[17179571.000000] ACPI: PCI Interrupt Link [APCF] (IRQs 20 21 22) *0, disabled.
[17179571.000000] ACPI: PCI Interrupt Link [APCG] (IRQs 20 21 22) *0, disabled.
[17179571.004000] ACPI: PCI Interrupt Link [APCH] (IRQs 20 21 22) *0, disabled.
[17179571.004000] ACPI: PCI Interrupt Link [APCI] (IRQs 20 21 22) *0, disabled.
[17179571.004000] ACPI: PCI Interrupt Link [APCJ] (IRQs 20 21 22) *0, disabled.
[17179571.004000] ACPI: PCI Interrupt Link [APCK] (IRQs 20 21 22) *0, disabled.
[17179571.004000] ACPI: PCI Interrupt Link [APCS] (IRQs *23), disabled.
[17179571.004000] ACPI: PCI Interrupt Link [APCL] (IRQs 20 21 22) *0, disabled.
[17179571.008000] ACPI: PCI Interrupt Link [APCM] (IRQs 20 21 22) *0, disabled.
[17179571.008000] ACPI: PCI Interrupt Link [AP3C] (IRQs 20 21 22) *0, disabled.
[17179571.008000] ACPI: PCI Interrupt Link [APCZ] (IRQs 20 21 22) *0, disabled.
[17179571.012000] Linux Plug and Play Support v0.97 (c) Adam Belay
[17179571.012000] pnp: PnP ACPI init
[17179571.016000] pnp: PnP ACPI: found 14 devices
[17179571.016000] PnPBIOS: Disabled by ACPI PNP
[17179571.016000] PCI: Using ACPI for IRQ routing
[17179571.016000] PCI: If a device doesn't work, try "pci=routeirq". If it helps, post a report
[17179571.068000] pnp: 00:00: ioport range 0x4000-0x407f could not be reserved
[17179571.068000] pnp: 00:00: ioport range 0x4080-0x40ff has been reserved
[17179571.068000] pnp: 00:00: ioport range 0x4400-0x447f has been reserved
[17179571.068000] pnp: 00:00: ioport range 0x4480-0x44ff could not be reserved
[17179571.068000] pnp: 00:00: ioport range 0x4200-0x427f has been reserved
[17179571.068000] pnp: 00:00: ioport range 0x4280-0x42ff has been reserved
[17179571.068000] pnp: 00:01: ioport range 0x5000-0x503f has been reserved
[17179571.068000] pnp: 00:01: ioport range 0x5100-0x513f has been reserved
[17179571.068000] PCI: Bridge: 0000:00:08.0
[17179571.068000] IO window: 9000-afff
[17179571.068000] MEM window: e6000000-e7ffffff
[17179571.068000] PREFETCH window: 50000000-500fffff
[17179571.068000] PCI: Bridge: 0000:00:1e.0
[17179571.068000] IO window: disabled.
[17179571.068000] MEM window: e4000000-e5ffffff
[17179571.068000] PREFETCH window: d0000000-dfffffff
[17179571.068000] PCI: Setting latency timer of device 0000:00:08.0 to 64
[17179571.068000] NET: Registered protocol family 2
[17179571.108000] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
[17179571.108000] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
[17179571.108000] TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
[17179571.108000] TCP: Hash tables configured (established 131072 bind 65536)
[17179571.108000] TCP reno registered
[17179571.112000] audit: initializing netlink socket (disabled)
[17179571.112000] audit(1164050052.112:1): initialized
[17179571.112000] highmem bounce pool size: 64 pages
[17179571.112000] VFS: Disk quotas dquot_6.5.1
[17179571.112000] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[17179571.112000] Initializing Cryptographic API
[17179571.112000] io scheduler noop registered
[17179571.112000] io scheduler anticipatory registered
[17179571.112000] io scheduler deadline registered
[17179571.112000] io scheduler cfq registered (default)
[17179571.112000] isapnp: Scanning for PnP cards...
[17179571.468000] isapnp: No Plug & Play device found
[17179571.488000] Real Time Clock Driver v1.12ac
[17179571.488000] Serial: 8250/16550 driver $Revision: 1.90 $ 4 ports, IRQ sharing enabled
[17179571.488000] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[17179571.488000] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[17179571.488000] 00:0a: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[17179571.488000] 00:0b: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[17179571.488000] mice: PS/2 mouse device common for all mice
[17179571.492000] RAMDISK driver initialized: 16 RAM disks of 65536K size 1024 blocksize
[17179571.492000] Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
[17179571.492000] ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
[17179571.492000] PNP: PS/2 Controller [PNP0303:PS2K] at 0x60,0x64 irq 1
[17179571.492000] PNP: PS/2 controller doesn't have AUX irq; using default 12
[17179571.492000] serio: i8042 AUX port at 0x60,0x64 irq 12
[17179571.492000] serio: i8042 KBD port at 0x60,0x64 irq 1
[17179571.492000] EISA: Probing bus 0 at eisa.0
[17179571.492000] Cannot allocate resource for EISA slot 4
[17179571.492000] Cannot allocate resource for EISA slot 5
[17179571.492000] EISA: Detected 0 cards.
[17179571.492000] TCP bic registered
[17179571.496000] NET: Registered protocol family 1
[17179571.496000] NET: Registered protocol family 8
[17179571.496000] NET: Registered protocol family 20
[17179571.496000] Using IPI No-Shortcut mode
[17179571.496000] ACPI: (supports S0 S1 S4 S5)
[17179571.496000] Freeing unused kernel memory: 308k freed
[17179571.548000] Capability LSM initialized
[17179571.580000] ACPI: Fan [FAN] (on)
[17179571.588000] ACPI: Thermal Zone [THRM] (43 C)
[17179571.704000] input: AT Translated Set 2 keyboard as /class/input/input0
[17179572.072000] NFORCE2: IDE controller at PCI slot 0000:00:09.0
[17179572.072000] NFORCE2: chipset revision 162
[17179572.072000] NFORCE2: not 100% native mode: will probe irqs later
[17179572.072000] NFORCE2: BIOS didn't set cable bits correctly. Enabling workaround.
[17179572.072000] NFORCE2: BIOS didn't set cable bits correctly. Enabling workaround.
[17179572.072000] NFORCE2: 0000:00:09.0 (rev a2) UDMA133 controller
[17179572.072000] ide0: BM-DMA at 0xf000-0xf007, BIOS settings: hda:DMA, hdb:DMA
[17179572.072000] ide1: BM-DMA at 0xf008-0xf00f, BIOS settings: hdc:DMA, hdd:DMA
[17179572.072000] Probing IDE interface ide0...
[17179572.368000] hda: WDC WD800BB-00JHC0, ATA DISK drive
[17179572.816000] hdb: PIONEER DVD-RW DVR-105, ATAPI CD/DVD-ROM drive
[17179572.872000] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
[17179572.880000] Probing IDE interface ide1...
[17179573.620000] hdc: HL-DT-STDVD+-RW GSA-H21L, ATAPI CD/DVD-ROM drive
[17179574.404000] hdd: LG CD-RW CED-8080B, ATAPI CD/DVD-ROM drive
[17179574.460000] ide1 at 0x170-0x177,0x376 on irq 15
[17179574.472000] SCSI subsystem initialized
[17179574.472000] libata version 1.20 loaded.
[17179574.484000] hda: max request size: 128KiB
[17179574.496000] hda: 156301488 sectors (80026 MB) w/2048KiB Cache, CHS=65535/16/63, UDMA(100)
[17179574.496000] hda: cache flushes supported
[17179574.496000] hda: hda1
[17179574.504000] hdb: ATAPI 32X DVD-ROM DVD-R CD-R/RW drive, 2000kB Cache, UDMA(33)
[17179574.504000] Uniform CD-ROM driver Revision: 3.20
[17179574.552000] hdc: ATAPI 48X DVD-ROM DVD-R CD-R/RW drive, 2048kB Cache, UDMA(44)
[17179574.556000] hdd: ATAPI 32X CD-ROM CD-R/RW drive, 2048kB Cache, UDMA(33)
[17179574.748000] sata_sil 0000:01:0b.0: version 0.9
[17179574.748000] ACPI: PCI Interrupt Link [APC3] enabled at IRQ 18
[17179574.748000] ACPI: PCI Interrupt 0000:01:0b.0[A] -> Link [APC3] -> GSI 18 (level, high) -> IRQ 177
[17179574.748000] ata1: SATA max UDMA/100 cmd 0xF885C080 ctl 0xF885C08A bmdma 0xF885C000 irq 177
[17179574.748000] ata2: SATA max UDMA/100 cmd 0xF885C0C0 ctl 0xF885C0CA bmdma 0xF885C008 irq 177
[17179575.108000] ata1: SATA link up 1.5 Gbps (SStatus 113)
[17179575.116000] ata1: dev 0 cfg 49:2f00 82:7c6b 83:7b09 84:4003 85:7c69 86:3a01 87:4003 88:207f
[17179575.116000] ata1: dev 0 ATA-7, max UDMA/133, 160086528 sectors: LBA
[17179575.124000] ata1: dev 0 configured for UDMA/100
[17179575.124000] scsi0 : sata_sil
[17179575.484000] ata2: SATA link up 1.5 Gbps (SStatus 113)
[17179575.492000] ata2: dev 0 cfg 49:2f00 82:7c6b 83:7b09 84:4003 85:7c69 86:3a01 87:4003 88:207f
[17179575.492000] ata2: dev 0 ATA-7, max UDMA/133, 160086528 sectors: LBA
[17179575.500000] ata2: dev 0 configured for UDMA/100
[17179575.500000] scsi1 : sata_sil
[17179575.500000] Vendor: ATA Model: Maxtor 6Y080M0 Rev: YAR5
[17179575.500000] Type: Direct-Access ANSI SCSI revision: 05
[17179575.500000] Vendor: ATA Model: Maxtor 6Y080M0 Rev: YAR5
[17179575.500000] Type: Direct-Access ANSI SCSI revision: 05
[17179575.512000] SCSI device sda: 160086528 512-byte hdwr sectors (81964 MB)
[17179575.512000] sda: Write Protect is off
[17179575.512000] sda: Mode Sense: 00 3a 00 00
[17179575.512000] SCSI device sda: drive cache: write back
[17179575.512000] SCSI device sda: 160086528 512-byte hdwr sectors (81964 MB)
[17179575.512000] sda: Write Protect is off
[17179575.512000] sda: Mode Sense: 00 3a 00 00
[17179575.512000] SCSI device sda: drive cache: write back
[17179575.512000] sda: sda1 sda2 sda3 sda4
[17179575.532000] sd 0:0:0:0: Attached scsi disk sda
[17179575.536000] SCSI device sdb: 160086528 512-byte hdwr sectors (81964 MB)
[17179575.536000] sdb: Write Protect is off
[17179575.536000] sdb: Mode Sense: 00 3a 00 00
[17179575.540000] SCSI device sdb: drive cache: write back
[17179575.544000] SCSI device sdb: 160086528 512-byte hdwr sectors (81964 MB)
[17179575.544000] sdb: Write Protect is off
[17179575.544000] sdb: Mode Sense: 00 3a 00 00
[17179575.544000] SCSI device sdb: drive cache: write back
[17179575.548000] sdb: sdb1 sdb2 sdb3 sdb4
[17179575.572000] sd 1:0:0:0: Attached scsi disk sdb
[17179575.788000] usbcore: registered new driver usbfs
[17179575.788000] usbcore: registered new driver hub
[17179575.788000] ohci_hcd: 2005 April 22 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI)
[17179575.788000] ACPI: PCI Interrupt Link [APCF] enabled at IRQ 22
[17179575.788000] ACPI: PCI Interrupt 0000:00:02.0[A] -> Link [APCF] -> GSI 22 (level, high) -> IRQ 185
[17179575.788000] PCI: Setting latency timer of device 0000:00:02.0 to 64
[17179575.788000] ohci_hcd 0000:00:02.0: OHCI Host Controller
[17179575.788000] ohci_hcd 0000:00:02.0: new USB bus registered, assigned bus number 1
[17179575.788000] ohci_hcd 0000:00:02.0: irq 185, io mem 0xe8080000
[17179575.800000] ieee1394: Initialized config rom entry `ip1394'
[17179575.844000] usb usb1: configuration #1 chosen from 1 choice
[17179575.844000] hub 1-0:1.0: USB hub found
[17179575.844000] hub 1-0:1.0: 3 ports detected
[17179575.948000] ACPI: PCI Interrupt Link [APCG] enabled at IRQ 21
[17179575.948000] ACPI: PCI Interrupt 0000:00:02.1[B] -> Link [APCG] -> GSI 21 (level, high) -> IRQ 193
[17179575.948000] PCI: Setting latency timer of device 0000:00:02.1 to 64
[17179575.948000] ohci_hcd 0000:00:02.1: OHCI Host Controller
[17179575.948000] ohci_hcd 0000:00:02.1: new USB bus registered, assigned bus number 2
[17179575.948000] ohci_hcd 0000:00:02.1: irq 193, io mem 0xe8083000
[17179576.004000] usb usb2: configuration #1 chosen from 1 choice
[17179576.004000] hub 2-0:1.0: USB hub found
[17179576.004000] hub 2-0:1.0: 3 ports detected
[17179576.128000] ACPI: PCI Interrupt Link [APCM] enabled at IRQ 20
[17179576.128000] ACPI: PCI Interrupt 0000:00:0d.0[A] -> Link [APCM] -> GSI 20 (level, high) -> IRQ 201
[17179576.128000] PCI: Setting latency timer of device 0000:00:0d.0 to 64
[17179576.180000] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[201] MMIO=[e8084000-e80847ff] Max Packet=[2048] IR/IT contexts=[4/4]
[17179576.188000] ACPI: PCI Interrupt Link [APCL] enabled at IRQ 22
[17179576.188000] ACPI: PCI Interrupt 0000:00:02.2[C] -> Link [APCL] -> GSI 22 (level, high) -> IRQ 185
[17179576.188000] PCI: Setting latency timer of device 0000:00:02.2 to 64
[17179576.188000] ehci_hcd 0000:00:02.2: EHCI Host Controller
[17179576.188000] ehci_hcd 0000:00:02.2: new USB bus registered, assigned bus number 3
[17179576.188000] ehci_hcd 0000:00:02.2: selective suspend/wakeup unavailable
[17179576.188000] ehci_hcd 0000:00:02.2: debug port 1
[17179576.188000] PCI: cache line size of 64 is not supported by device 0000:00:02.2
[17179576.188000] ehci_hcd 0000:00:02.2: irq 185, io mem 0xe8086000
[17179576.188000] ehci_hcd 0000:00:02.2: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
[17179576.188000] usb usb3: configuration #1 chosen from 1 choice
[17179576.188000] hub 3-0:1.0: USB hub found
[17179576.188000] hub 3-0:1.0: 6 ports detected
[17179576.368000] md: md driver 0.90.3 MAX_MD_DEVS=256, MD_SB_DISKS=27
[17179576.372000] md: bitmap version 4.39
[17179576.372000] md: raid1 personality registered for level 1
[17179576.432000] md: raid0 personality registered for level 0
[17179577.168000] ohci_hcd 0000:00:02.0: wakeup
[17179577.296000] ohci1394: fw-host0: SelfID received outside of bus reset sequence
[17179577.336000] ohci_hcd 0000:00:02.1: wakeup
[17179577.568000] ieee1394: Host added: ID:BUS[0-00:1023] GUID[000000508dfee044]
[17179577.720000] usb 1-1: new low speed USB device using ohci_hcd and address 3
[17179577.932000] usb 1-1: configuration #1 chosen from 1 choice
[17179578.240000] usb 1-2: new full speed USB device using ohci_hcd and address 4
[17179583.468000] usb 1-2: configuration #1 chosen from 1 choice
[17179583.772000] usb 2-3: new low speed USB device using ohci_hcd and address 2
[17179583.984000] usb 2-3: configuration #1 chosen from 1 choice
[17179583.992000] usbcore: registered new driver hiddev
[17179584.004000] input: Logitech USB Receiver as /class/input/input1
[17179584.004000] input: USB HID v1.10 Mouse [Logitech USB Receiver] on usb-0000:00:02.0-1
[17179584.016000] input: PS2 to USB as /class/input/input2
[17179584.016000] input: USB HID v1.10 Keyboard [PS2 to USB] on usb-0000:00:02.1-3
[17179584.032000] input: PS2 to USB as /class/input/input3
[17179584.032000] input: USB HID v1.10 Mouse [PS2 to USB] on usb-0000:00:02.1-3
[17179584.032000] usbcore: registered new driver usbhid
[17179584.032000] drivers/usb/input/hid-core.c: v2.6:USB HID core driver

---
After showing above line, nothing happens for about 6 minutes. No harddrive activity visible. Then it shows below lines and go on with rest of startup sequence without problems.
---

[17179963.624000] md: md0 stopped.
[17179963.624000] md: bind<sdb>
[17179963.628000] md: bind<sda>
[17179963.632000] md0: setting max_sectors to 32, segment boundary to 8191
[17179963.632000] raid0: looking at sda
[17179963.632000] raid0: comparing sda(80043200) with sda(80043200)
[17179963.632000] raid0: END
[17179963.632000] raid0: ==> UNIQUE
[17179963.632000] raid0: 1 zones
[17179963.632000] raid0: looking at sdb
[17179963.632000] raid0: comparing sdb(80043200) with sda(80043200)
[17179963.632000] raid0: EQUAL
[17179963.632000] raid0: FINAL 1 zones
[17179963.632000] raid0: done.
[17179963.632000] raid0 : md_size is 160086400 blocks.
[17179963.632000] raid0 : conf->hash_spacing is 160086400 blocks.
[17179963.632000] raid0 : nb_zone is 1.
[17179963.632000] raid0 : Allocating 4 bytes for hash.
[17179963.648000] md: md1 stopped.
[17179963.652000] md: bind<sdb1>
[17179963.652000] md: bind<sda1>
[17179963.656000] raid1: raid set md1 active with 2 out of 2 mirrors
[17179963.676000] md: md2 stopped.
[17179963.680000] md: bind<sdb3>
[17179963.680000] md: bind<sda3>
[17179963.684000] md2: setting max_sectors to 32, segment boundary to 8191
[17179963.684000] raid0: looking at sda3
[17179963.684000] raid0: comparing sda3(76975360) with sda3(76975360)
[17179963.684000] raid0: END
[17179963.684000] raid0: ==> UNIQUE
[17179963.684000] raid0: 1 zones
[17179963.684000] raid0: looking at sdb3
[17179963.684000] raid0: comparing sdb3(76975360) with sda3(76975360)
[17179963.684000] raid0: EQUAL
[17179963.684000] raid0: FINAL 1 zones
[17179963.684000] raid0: done.
[17179963.684000] raid0 : md_size is 153950720 blocks.
[17179963.684000] raid0 : conf->hash_spacing is 153950720 blocks.
[17179963.684000] raid0 : nb_zone is 1.
[17179963.684000] raid0 : Allocating 4 bytes for hash.
[17179963.708000] md: md3 stopped.
[17179963.712000] md: bind<sdb4>
[17179963.712000] md: bind<sda4>
[17179963.720000] md3: setting max_sectors to 128, segment boundary to 32767
[17179963.720000] raid0: looking at sda4
[17179963.720000] raid0: comparing sda4(2449792) with sda4(2449792)
[17179963.720000] raid0: END
[17179963.720000] raid0: ==> UNIQUE
[17179963.720000] raid0: 1 zones
[17179963.720000] raid0: looking at sdb4
[17179963.720000] raid0: comparing sdb4(2449792) with sda4(2449792)
[17179963.720000] raid0: EQUAL
[17179963.720000] raid0: FINAL 1 zones
[17179963.720000] raid0: done.
[17179963.720000] raid0 : md_size is 4899584 blocks.
[17179963.720000] raid0 : conf->hash_spacing is 4899584 blocks.
[17179963.720000] raid0 : nb_zone is 1.
[17179963.720000] raid0 : Allocating 4 bytes for hash.
[17179963.960000] Attempting manual resume
[17179963.980000] ReiserFS: md3: found reiserfs format "3.6" with standard journal
[17179964.292000] ReiserFS: md3: using ordered data mode
[17179964.312000] ReiserFS: md3: journal params: device md3, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
[17179964.312000] ReiserFS: md3: checking transaction log (md3)
[17179964.352000] ReiserFS: md3: Using r5 hash to sort names
[17179967.408000] Linux agpgart interface v0.101 (c) Dave Jones
[17179967.456000] agpgart: Detected NVIDIA nForce2 chipset
[17179967.460000] agpgart: AGP aperture is 64M @ 0xe0000000
[17179967.644000] i2c_adapter i2c-0: nForce2 SMBus adapter at 0x5000
[17179967.644000] i2c_adapter i2c-1: nForce2 SMBus adapter at 0x5100
[17179968.020000] forcedeth.c: Reverse Engineered nForce ethernet driver. Version 0.54.
[17179968.020000] ACPI: PCI Interrupt Link [APCH] enabled at IRQ 21
[17179968.020000] ACPI: PCI Interrupt 0000:00:04.0[A] -> Link [APCH] -> GSI 21 (level, high) -> IRQ 193
[17179968.020000] PCI: Setting latency timer of device 0000:00:04.0 to 64
[17179968.436000] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[17179968.484000] Floppy drive(s): fd0 is 1.44M
[17179968.504000] FDC 0 is a post-1991 82077
[17179968.540000] eth0: forcedeth.c: subsystem: 0147b:1c00 bound to 0000:00:04.0
[17179968.600000] parport: PnPBIOS parport detected.
[17179968.600000] parport0: PC-style at 0x378 (0x778), irq 7, dma 3 [PCSPP,TRISTATE,COMPAT,EPP,ECP,DMA]
[17179968.632000] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[17179968.860000] input: PC Speaker as /class/input/input4
[17179969.084000] eth0: no link during initialization.
[17179969.240000] nvidia: module license 'NVIDIA' taints kernel.
[17179969.244000] ACPI: PCI Interrupt Link [APC4] enabled at IRQ 19
[17179969.244000] ACPI: PCI Interrupt 0000:02:00.0[A] -> Link [APC4] -> GSI 19 (level, high) -> IRQ 209
[17179969.244000] NVRM: loading NVIDIA Linux x86 Kernel Module 1.0-8776 Mon Oct 16 21:56:04 PDT 2006
[17179969.548000] drivers/usb/class/usblp.c: usblp0: USB Bidirectional printer dev 4 if 0 alt 0 proto 2 vid 0x03F0 pid 0x7104
[17179969.548000] usbcore: registered new driver usblp
[17179969.548000] drivers/usb/class/usblp.c: v0.13: USB Printer Device Class driver
[17179969.548000] ACPI: PCI Interrupt 0000:01:07.0[A] -> Link [APC4] -> GSI 19 (level, high) -> IRQ 209
[17179969.548000] rt2500 1.1.0 BETA4 2006/06/18 http://rt2x00.serialmonkey.com
[17179969.608000] ACPI: PCI Interrupt Link [APCJ] enabled at IRQ 20
[17179969.608000] ACPI: PCI Interrupt 0000:00:06.0[A] -> Link [APCJ] -> GSI 20 (level, high) -> IRQ 201
[17179969.608000] PCI: Setting latency timer of device 0000:00:06.0 to 64
[17179969.656000] eth0: link up.
[17179969.688000] ts: Compaq touchscreen protocol output
[17179969.764000] sd 0:0:0:0: Attached scsi generic sg0 type 0
[17179969.764000] sd 1:0:0:0: Attached scsi generic sg1 type 0
[17179969.944000] intel8x0_measure_ac97_clock: measured 67375 usecs
[17179969.944000] intel8x0: clocking to 48000
[17179970.128000] NET: Registered protocol family 17
[17179970.172000] lp0: using parport0 (interrupt-driven).
[17179970.188000] ieee1394: sbp2: Driver forced to serialize I/O (serialize_io=1)
[17179970.188000] ieee1394: sbp2: Try serialize_io=0 for better performance
[17179970.216000] Adding 489972k swap on /dev/disk/by-uuid/4963b1b8-71de-4ebc-95c7-d38b1e8c827e. Priority:-1 extents:1 across:489972k
[17179970.228000] Adding 489972k swap on /dev/disk/by-uuid/a16b4331-8e05-4a01-a48b-f1a0b4c6b25a. Priority:-2 extents:1 across:489972k
[17179975.684000] device-mapper: 4.6.0-ioctl (2006-02-17) initialised: <email address hidden>
[17179988.940000] ReiserFS: md1: found reiserfs format "3.6" with standard journal
[17179988.940000] ReiserFS: md1: using ordered data mode
[17179988.952000] ReiserFS: md1: journal params: device md1, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
[17179988.952000] ReiserFS: md1: checking transaction log (md1)
[17179989.008000] ReiserFS: md1: Using r5 hash to sort names
[17179989.032000] ReiserFS: dm-1: found reiserfs format "3.6" with standard journal
[17179990.040000] ReiserFS: dm-1: using ordered data mode
[17179990.052000] ReiserFS: dm-1: journal params: device dm-1, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
[17179990.056000] ReiserFS: dm-1: checking transaction log (dm-1)
[17179990.096000] ReiserFS: dm-1: Using r5 hash to sort names
[17179990.144000] ReiserFS: dm-0: found reiserfs format "3.6" with standard journal
[17179991.164000] ReiserFS: dm-0: using ordered data mode
[17179991.284000] ReiserFS: dm-0: journal params: device dm-0, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
[17179991.288000] ReiserFS: dm-0: checking transaction log (dm-0)
[17179991.352000] ReiserFS: dm-0: Using r5 hash to sort names
[17179994.884000] pcc_acpi: loading...
[17179994.896000] ACPI: Power Button (FF) [PWRF]
[17179994.896000] ACPI: Power Button (CM) [PWRB]
[17179994.940000] ibm_acpi: ec object not found
[17179997.140000] agpgart: Found an AGP 3.0 compliant device at 0000:00:00.0.
[17179997.140000] agpgart: Putting AGP V3 device at 0000:00:00.0 into 8x mode
[17179997.140000] agpgart: Putting AGP V3 device at 0000:02:00.0 into 8x mode
[17179997.536000] apm: BIOS version 1.2 Flags 0x07 (Driver version 1.16ac)
[17179997.536000] apm: overridden by ACPI.
[17180004.744000] Bluetooth: Core ver 2.8
[17180004.744000] NET: Registered protocol family 31
[17180004.744000] Bluetooth: HCI device and connection manager initialized
[17180004.744000] Bluetooth: HCI socket layer initialized
[17180004.764000] Bluetooth: L2CAP ver 2.8
[17180004.764000] Bluetooth: L2CAP socket layer initialized
[17180004.784000] Bluetooth: RFCOMM socket layer initialized
[17180004.784000] Bluetooth: RFCOMM TTY layer initialized
[17180004.784000] Bluetooth: RFCOMM ver 1.7
[17180025.136000] NET: Registered protocol family 10
[17180025.140000] lo: Disabled Privacy Extensions
[17180025.140000] IPv6 over IPv4 tunneling driver
[17180035.200000] eth0: no IPv6 routers present