Very slow boot in Ubuntu Server

BloodBlight · 12-03-2010, 10:41 PM

Hey everyone, I have an Ubuntu Server 10.10 install on a VIA C7 chip. It seems to pause for about 5 min on a USB component.

It is a fresh install, here is a snip from my messages log:

Quote:

Dec 3 20:18:05 MediaServer kernel: [ 0.467857] ehci_hcd 0000:00:10.4: new USB bus registered, assigned bus number 1
Dec 3 20:18:05 MediaServer kernel: [ 0.467857] ehci_hcd 0000:00:10.4: irq 21, io mem 0xfdffc000
Dec 3 20:18:05 MediaServer kernel: [ 300.066846] ehci_hcd 0000:00:10.4: USB 2.0 started, EHCI 1.00
Dec 3 20:18:05 MediaServer kernel: [ 300.067363] hub 1-0:1.0: USB hub found
Dec 3 20:18:05 MediaServer kernel: [ 300.067434] hub 1-0:1.0: 8 ports detected

My KB is USB, so I would rather not disable it if at all possible. The next time I can take it down, I will test disabling it though...

paulsm4 · 12-04-2010, 01:01 AM

Q: What are the messages immediately BEFORE and immediately AFTER the 5-minute hang?

Q: Do you happen to have a USB hard drive connected to the bus in question?

Q: If yes, does the USB HD have a lot of files on it?

ALSO:
Check out these links; see if any might be relevant:
http://forum.wdlxtv.com/viewtopic.php?f=32&t=964

http://forums.plexapp.com/index.php/...-not-crashing/

http://forum1.netgear.com/showthread.php?p=303421

BloodBlight · 12-04-2010, 10:47 AM

Quote:

Q: What are the messages immediately BEFORE and immediately AFTER the 5-minute hang?

There are no error messages at all, just the long delay. Here is an extended output from the message log (as it mirrors what is on the screen at the time), and I have marked the point of the pause. (at the bottom of the post)

Quote:

Q: Do you happen to have a USB hard drive connected to the bus in question?

Nope, in-fact I usually boot the server with no USB devices at all (including the KB). However, here are the devices as of right now:

Code:

root@MediaServer:~$ lsusb
Bus 005 Device 002: ID 045e:00b0 Microsoft Corp. Digital Media Pro Keyboard
Bus 005 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 004 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 003 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 002 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub

Quote:

Q: If yes, does the USB HD have a lot of files on it?

N/A

I am reading though your links now, thanks!

I AM having another issue with an raid5 array, I will link to it as a separate thread.

/var/log/messages

Code:

*I removed the prefixes to fit the forum better.
[    0.370223] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.372389] TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
[    0.373510] TCP: Hash tables configured (established 131072 bind 65536)
[    0.373584] TCP reno registered
[    0.373652] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.373746] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.374058] NET: Registered protocol family 1
[    0.374182] pci 0000:00:01.0: disabling DAC on VIA PCI bridge
[    0.374398] pci 0000:00:11.0: Bypassing VIA 8237 APIC De-Assert Message
[    0.374695] cpufreq-nforce2: No nForce2 chipset.
[    0.374846] Scanning for low memory corruption every 60 seconds
[    0.375276] audit: initializing netlink socket (disabled)
[    0.375363] type=2000 audit(1291435957.368:1): initialized
[    0.405717] Trying to unpack rootfs image as initramfs...
[    0.440286] highmem bounce pool size: 64 pages
[    0.440371] HugeTLB registered 4 MB page size, pre-allocated 0 pages
[    0.452773] VFS: Disk quotas dquot_6.5.2
[    0.453116] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.455040] fuse init (API version 7.14)
[    0.455414] msgmni has been set to 1709
[    0.461218] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[    0.461308] io scheduler noop registered
[    0.461370] io scheduler deadline registered
[    0.461472] io scheduler cfq registered (default)
[    0.461890] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.462014] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    0.462600] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
[    0.462692] ACPI: Power Button [PWRB]
[    0.462922] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[    0.463004] ACPI: Power Button [PWRF]
[    0.463184] ACPI: Fan [FAN] (on)
[    0.464127] Marking TSC unstable due to TSC halts in idle
[    0.467857] thermal LNXTHERM:01: registered as thermal_zone0
[    0.467857] ACPI: Thermal Zone [THRM] (40 C)
[    0.467857] ERST: Table is not found!
[    0.467857] Freeing initrd memory: 11532k freed
[    0.467857] isapnp: Scanning for PnP cards...
[    0.467857] isapnp: No Plug & Play device found
[    0.467857] Switching to clocksource hpet
[    0.467857] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.467857] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[    0.467857] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[    0.467857] 00:09: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[    0.467857] 00:0a: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
[    0.467857] brd: module loaded
[    0.467857] loop: module loaded
[    0.467857] ACPI: PCI Interrupt Link [ALKA] enabled at IRQ 20
[    0.467857] pata_acpi 0000:00:0f.0: PCI INT B -> Link[ALKA] -> GSI 20 (level, low) -> IRQ 20
[    0.467857] pata_acpi 0000:00:0f.0: PCI INT B disabled
[    0.467857] pata_acpi 0000:00:0f.1: PCI INT A -> Link[ALKA] -> GSI 20 (level, low) -> IRQ 20
[    0.467857] pata_acpi 0000:00:0f.1: PCI INT A disabled
[    0.467857] Fixed MDIO Bus: probed
[    0.467857] PPP generic driver version 2.4.2
[    0.467857] tun: Universal TUN/TAP device driver, 1.6
[    0.467857] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    0.467857] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.467857] ACPI: PCI Interrupt Link [ALKB] enabled at IRQ 21
[    0.467857] ehci_hcd 0000:00:10.4: PCI INT C -> Link[ALKB] -> GSI 21 (level, low) -> IRQ 21
[    0.467857] ehci_hcd 0000:00:10.4: EHCI Host Controller
[    0.467857] ehci_hcd 0000:00:10.4: new USB bus registered, assigned bus number 1
[    0.467857] ehci_hcd 0000:00:10.4: irq 21, io mem 0xfdffc000

>>PAUSE<<

[  300.066846] ehci_hcd 0000:00:10.4: USB 2.0 started, EHCI 1.00
[  300.067363] hub 1-0:1.0: USB hub found
[  300.067434] hub 1-0:1.0: 8 ports detected
[  300.067694] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[  300.067804] uhci_hcd: USB Universal Host Controller Interface driver
[  300.068000] uhci_hcd 0000:00:10.0: PCI INT A -> Link[ALKB] -> GSI 21 (level, low) -> IRQ 21
[  300.068133] uhci_hcd 0000:00:10.0: UHCI Host Controller
[  300.068329] uhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 2
[  300.068445] uhci_hcd 0000:00:10.0: irq 21, io base 0x0000f800
[  300.068868] hub 2-0:1.0: USB hub found
[  300.068937] hub 2-0:1.0: 2 ports detected
[  300.069159] uhci_hcd 0000:00:10.1: PCI INT A -> Link[ALKB] -> GSI 21 (level, low) -> IRQ 21
[  300.069950] uhci_hcd 0000:00:10.1: UHCI Host Controller
[  300.070109] uhci_hcd 0000:00:10.1: new USB bus registered, assigned bus number 3
[  300.070217] uhci_hcd 0000:00:10.1: irq 21, io base 0x0000f700
[  300.070603] hub 3-0:1.0: USB hub found
[  300.070673] hub 3-0:1.0: 2 ports detected
[  300.070882] uhci_hcd 0000:00:10.2: PCI INT B -> Link[ALKB] -> GSI 21 (level, low) -> IRQ 21
[  300.070969] uhci_hcd 0000:00:10.2: UHCI Host Controller
[  300.071131] uhci_hcd 0000:00:10.2: new USB bus registered, assigned bus number 4
[  300.071236] uhci_hcd 0000:00:10.2: irq 21, io base 0x0000f600
[  300.071633] hub 4-0:1.0: USB hub found
[  300.071700] hub 4-0:1.0: 2 ports detected
[  300.071902] uhci_hcd 0000:00:10.3: PCI INT B -> Link[ALKB] -> GSI 21 (level, low) -> IRQ 21
[  300.071990] uhci_hcd 0000:00:10.3: UHCI Host Controller
[  300.072221] uhci_hcd 0000:00:10.3: new USB bus registered, assigned bus number 5
[  300.072332] uhci_hcd 0000:00:10.3: irq 21, io base 0x0000f500
[  300.072727] hub 5-0:1.0: USB hub found
[  300.072795] hub 5-0:1.0: 2 ports detected
[  300.073165] PNP: No PS/2 controller found. Probing ports directly.
[  300.324115] serio: i8042 KBD port at 0x60,0x64 irq 1
[  300.324482] mice: PS/2 mouse device common for all mice
[  300.324856] rtc_cmos 00:06: RTC can wake from S4
[  300.325051] rtc_cmos 00:06: rtc core: registered rtc_cmos as rtc0
[  300.325167] rtc0: alarms up to one year, y3k, 242 bytes nvram, hpet irqs
[  300.325589] device-mapper: uevent: version 1.0.3
[  300.326009] device-mapper: ioctl: 4.17.0-ioctl (2010-03-05) initialised: dm-devel@redhat.com
[  300.326223] device-mapper: multipath: version 1.1.1 loaded
[  300.326291] device-mapper: multipath round-robin: version 1.0.0 loaded
[  300.326724] EISA: Probing bus 0 at eisa.0
[  300.326790] EISA: Cannot allocate resource for mainboard
[  300.326857] Cannot allocate resource for EISA slot 1
[  300.326922] Cannot allocate resource for EISA slot 2
[  300.326987] Cannot allocate resource for EISA slot 3
[  300.327052] Cannot allocate resource for EISA slot 4
[  300.327117] Cannot allocate resource for EISA slot 5
[  300.327182] Cannot allocate resource for EISA slot 6
[  300.327247] Cannot allocate resource for EISA slot 7
[  300.327312] Cannot allocate resource for EISA slot 8
[  300.327376] EISA: Detected 0 cards.
[  300.327644] cpuidle: using governor ladder
[  300.327863] cpuidle: using governor menu
[  300.328864] TCP cubic registered
[  300.328926] IPv6: Loaded, but administratively disabled, reboot required to enable
[  300.329005] NET: Registered protocol family 17
[  300.329723] Using IPI No-Shortcut mode
[  300.330085] registered taskstats version 1
[  300.330708]   Magic number: 6:924:210
[  300.331007] rtc_cmos 00:06: setting system clock to 2010-12-04 04:17:38 UTC (1291436258)
[  300.331089] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[  300.331155] EDD information not available.
[  300.331420] Freeing unused kernel memory: 684k freed
[  300.333005] Write protecting the kernel text: 4932k

BloodBlight · 12-04-2010, 11:00 AM

Other thread:
Drives change device ids while on-line!

paulsm4 · 12-04-2010, 04:35 PM

Hi -

Perplexing

Q: What exactly is the USB vendor:device code (i.e. what's the device)?

Suggestion:
Try disabling ehci_hcd and see if that helps:
http://www.thinkwiki.org/wiki/Problem_with_USB_2.0