Bug 10728 - Booting pauses for about 30 seconds on Acer Aspire 5710Z
Summary: Booting pauses for about 30 seconds on Acer Aspire 5710Z
Status: CLOSED DOCUMENTED
Alias: None
Product: Other
Classification: Unclassified
Component: Other (show other bugs)
Hardware: All Linux
: P1 normal
Assignee: other_other
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2008-05-16 14:33 UTC by Sebastian Krause
Modified: 2012-05-21 15:26 UTC (History)
2 users (show)

See Also:
Kernel Version: 2.6.26-rc2
Subsystem:
Regression: Yes
Bisected commit-id:


Attachments
My kernel configuration file for 2.6.26-rc2 (89.08 KB, application/octet-stream)
2008-05-16 14:35 UTC, Sebastian Krause
Details

Description Sebastian Krause 2008-05-16 14:33:38 UTC
Latest working kernel version: 2.6.23
Earliest failing kernel version: 2.6.24
Distribution: Debian Testing
Hardware Environment: Acer Aspire 5710Z (Laptop)
Software Environment:

The bug first happened with the Debian kernel 2.6.24, but I'm able to reproduce with a customly compiled vanilla 2.6.26-rc2 from git. The Debian bug report is at http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=479381 and I was recommended to forward it here. Here is the full report again:

Since version 2.6.24 booting the Debian kernel is quite slow because it
pauses for about 30 seconds. It always happens after those lines in the
booting process:

[    9.990058] iTCO_wdt: Found a ICH7-M TCO device (Version=2, TCOBASE=0x1060)
[    9.990091] iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)

Since the interval is "heartbeat=30 sec" and also the pause is always
about 30 seconds long, there might be a connection. The problem happens
on an Acer Aspire 5710Z laptop.

Sebastian

-- Package-specific info:
** Version:
Linux version 2.6.25-1-686 (Debian 2.6.25-1) (waldi@debian.org) (gcc version 4.1.3 20080420 (prerelease) (Debian 4.1.2-22)) #1 SMP Mon Apr 28 13:54:58 UTC 2008

** Command line:
root=/dev/sda5 ro quiet 

** Not tainted

** Kernel log:
[    8.027540] ACPI: WMI: Mapper loaded
[    8.099282] input: Power Button (FF) as /class/input/input2
[    8.285803] acer-wmi: Acer Laptop ACPI-WMI Extras version 0.1
[    8.145506] ACPI: Power Button (FF) [PWRF]
[    8.145576] input: Lid Switch as /class/input/input3
[    8.177615] ACPI: Lid Switch [LID]
[    8.177683] input: Sleep Button (CM) as /class/input/input4
[    8.241504] ACPI: Sleep Button (CM) [SLPB]
[    8.241580] input: Power Button (CM) as /class/input/input5
[    8.305592] ACPI: Power Button (CM) [PWRB]
[    8.324898] ACPI: AC Adapter [ACAD] (on-line)
[    8.337447] ACPI: device:03 is registered as cooling_device2
[    8.484265] input: Video Bus as /class/input/input6
[    8.535663] ACPI: Video Device [VGA] (multi-head: yes  rom: no  post: no)
[    8.565224] ACPI: device:07 is registered as cooling_device3
[    8.565372] input: Video Bus as /class/input/input7
[    8.615159] ACPI: Video Device [GFX0] (multi-head: yes  rom: no  post: no)
[    8.859224] ACPI: Battery Slot [BAT1] (battery present)
[    8.798578] intel_rng: FWH not detected
[    9.146404] input: PC Speaker as /class/input/input8
[    9.198700] Real Time Clock Driver v1.12ac
[    9.294007] ACPI: PCI Interrupt 0000:00:1f.3[B] -> GSI 19 (level, low) -> IRQ 19
[    9.339410] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.02 (26-Jul-2007)
[    9.341907] sdhci: Secure Digital Host Controller Interface driver
[    9.341910] sdhci: Copyright(c) Pierre Ossman
[    9.341941] sdhci: SDHCI controller found at 0000:06:00.1 [1524:0750] (rev 0)
[    9.341961] PCI: Enabling device 0000:06:00.1 (0000 -> 0002)
[    9.341970] ACPI: PCI Interrupt 0000:06:00.1[A] -> GSI 20 (level, low) -> IRQ 20
[    9.342007] PCI: Setting latency timer of device 0000:06:00.1 to 64
[    9.342065] mmc0: SDHCI at 0xd0200400 irq 20 DMA
[    9.342077] sdhci: SDHCI controller found at 0000:06:00.3 [1524:0751] (rev 0)
[    9.342094] PCI: Enabling device 0000:06:00.3 (0000 -> 0002)
[    9.342101] ACPI: PCI Interrupt 0000:06:00.3[A] -> GSI 20 (level, low) -> IRQ 20
[    9.342114] sdhc1:slot0: Will use DMA mode even though HW doesn't fully claim to support it.
[    9.342122] PCI: Setting latency timer of device 0000:06:00.3 to 64
[    9.342151] mmc1: SDHCI at 0xd0200100 irq 20 DMA
[    9.477742] b43-phy0: Broadcom 4311 WLAN found
[    9.545960] phy0: Selected rate control algorithm 'pid'
[    9.539405] Broadcom 43xx driver loaded [ Features: PMLR, Firmware-ID: FW13 ]
[    9.541948] udev: renamed network interface wmaster0 to eth1
[    9.632791] ACPI: PCI Interrupt 0000:00:1b.0[A] -> GSI 22 (level, low) -> IRQ 22
[    9.632824] PCI: Setting latency timer of device 0000:00:1b.0 to 64
[    9.864404] input: PS/2 Mouse as /class/input/input9
[    9.929540] input: AlpsPS/2 ALPS GlidePoint as /class/input/input10
[    9.990058] iTCO_wdt: Found a ICH7-M TCO device (Version=2, TCOBASE=0x1060)
[    9.990091] iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)
[   41.260551] Adding 1004020k swap on /dev/sda6.  Priority:-1 extents:1 across:1004020k
[   41.747905] EXT3 FS on sda5, internal journal
[   42.306225] loop: module loaded
[   42.563179] fuse init (API version 7.9)
[   48.165224] warning: `named' uses 32-bit capabilities (legacy support in use)
[   48.398454] NET: Registered protocol family 10
[   48.399064] lo: Disabled Privacy Extensions
[   48.399589] ADDRCONF(NETDEV_UP): eth0: link is not ready
[   48.965280] lp: driver loaded but no devices found
[   49.156939] ppdev: user-space parallel port driver
[   53.065655] Bluetooth: Core ver 2.11
[   53.067120] NET: Registered protocol family 31
[   53.067126] Bluetooth: HCI device and connection manager initialized
[   53.067131] Bluetooth: HCI socket layer initialized
[   53.430853] Bluetooth: L2CAP ver 2.9
[   53.430865] Bluetooth: L2CAP socket layer initialized
[   53.163425] Bluetooth: RFCOMM socket layer initialized
[   53.163448] Bluetooth: RFCOMM TTY layer initialized
[   53.163453] Bluetooth: RFCOMM ver 1.8
[   53.388785] input: b43-phy0 as /class/input/input11
[   53.844929] b43-phy0: Loading firmware version 410.2160 (2007-05-26 15:32:10)
[   55.697763] Registered led device: b43-phy0::tx
[   55.697791] Registered led device: b43-phy0::rx
[   55.697815] Registered led device: b43-phy0::radio
[   55.752358] ADDRCONF(NETDEV_UP): wlan0_rename: link is not ready
[   55.413717] hda: task_no_data_intr: status=0x51 { DriveReady SeekComplete Error }
[   55.413717] hda: task_no_data_intr: error=0x04 { AbortedCommand }
[   55.413717] ide: failed opcode was: 0xef
[   58.916339] [drm] Initialized drm 1.1.0 20060810
[   58.919673] ACPI: PCI Interrupt 0000:00:02.0[A] -> GSI 16 (level, low) -> IRQ 16
[   58.919684] PCI: Setting latency timer of device 0000:00:02.0 to 64
[   58.919759] [drm] Initialized i915 1.6.0 20060119 on minor 0

** Loaded modules:
Module                  Size  Used by
aes_i586                8256  1 
aes_generic            29160  1 aes_i586
i915                   27648  2 
drm                    72280  3 i915
rfkill_input            4768  0 
rfcomm                 36336  0 
l2cap                  22784  5 rfcomm
bluetooth              52900  4 rfcomm,l2cap
ppdev                   8676  0 
parport_pc             25860  0 
lp                     11012  0 
parport                33868  3 ppdev,parport_pc,lp
ipv6                  241988  22 
acpi_cpufreq            9228  2 
cpufreq_ondemand        8428  1 
cpufreq_powersave       1856  0 
cpufreq_conservative     7432  0 
cpufreq_stats           5216  0 
freq_table              4512  3 acpi_cpufreq,cpufreq_ondemand,cpufreq_stats
cpufreq_userspace       3812  0 
fuse                   44348  1 
loop                   16708  0 
joydev                 11328  0 
snd_hda_intel         310008  1 
snd_pcm_oss            37824  0 
arc4                    2016  2 
snd_pcm                68228  2 snd_hda_intel,snd_pcm_oss
ecb                     3552  2 
crypto_blkcipher       18116  1 ecb
snd_mixer_oss          14880  1 snd_pcm_oss
snd_seq_dummy           3780  0 
b43                   132060  0 
snd_seq_oss            28544  0 
snd_seq_midi            8064  0 
rfkill                  7252  3 rfkill_input,b43
snd_rawmidi            22528  1 snd_seq_midi
mac80211              121964  1 b43
snd_seq_midi_event      6976  2 snd_seq_oss,snd_seq_midi
sdhci                  16428  0 
iTCO_wdt               11204  0 
snd_seq                45872  6 snd_seq_dummy,snd_seq_oss,snd_seq_midi,snd_seq_midi_event
psmouse                35920  0 
i2c_i801                9520  0 
cfg80211               17800  1 mac80211
mmc_core               43740  1 sdhci
i2c_core               22548  1 i2c_i801
rtc                    14272  0 
pcspkr                  3136  0 
serio_raw               6532  0 
snd_timer              21320  2 snd_pcm,snd_seq
snd_seq_device          7564  5 snd_seq_dummy,snd_seq_oss,snd_seq_midi,snd_rawmidi,snd_seq
input_polldev           4968  1 b43
rng_core                4900  1 b43
snd                    48772  11 snd_hda_intel,snd_pcm_oss,snd_pcm,snd_mixer_oss,snd_seq_oss,snd_rawmidi,snd_seq,snd_timer,snd_seq_device
battery                 8448  0 
soundcore               7488  1 snd
video                  19728  0 
output                  3712  1 video
ac                      4448  0 
snd_page_alloc          9992  2 snd_hda_intel,snd_pcm
acer_wmi               10548  0 
button                  8240  0 
wmi                     7880  1 acer_wmi
intel_agp              25084  1 
agpgart                31720  3 drm,intel_agp
led_class               5284  2 b43,acer_wmi
evdev                  11328  9 
ext3                  120264  1 
jbd                    43444  1 ext3
mbcache                 8260  1 ext3
ide_cd_mod             32864  0 
cdrom                  31872  1 ide_cd_mod
ata_generic             8004  0 
usbhid                 40192  0 
hid                    36032  1 usbhid
ff_memless              5384  1 usbhid
sd_mod                 26072  3 
piix                    7176  0 [permanent]
ahci                   27336  2 
ide_pci_generic         4228  0 [permanent]
libata                142032  2 ata_generic,ahci
scsi_mod              138508  2 sd_mod,libata
ide_core              100312  3 ide_cd_mod,piix,ide_pci_generic
dock                   10448  1 libata
ehci_hcd               32620  0 
ssb                    30820  1 b43
pcmcia                 36044  2 b43,ssb
pcmcia_core            36436  2 b43,pcmcia
firmware_class          9088  2 b43,pcmcia
uhci_hcd               22352  0 
usbcore               129936  4 usbhid,ehci_hcd,uhci_hcd
tg3                    88804  0 
thermal                19100  0 
processor              39660  4 acpi_cpufreq,thermal
fan                     5636  0 

** PCI devices:
00:00.0 Host bridge [0600]: Intel Corporation Mobile 945GM/PM/GMS, 943/940GML and 945GT Express Memory Controller Hub [8086:27a0] (rev 03)
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort+ >SERR- <PERR- INTx-
	Latency: 0
	Capabilities: <access denied>
	Kernel driver in use: agpgart-intel
	Kernel modules: intel-agp

00:02.0 VGA compatible controller [0300]: Intel Corporation Mobile 945GM/GMS, 943/940GML Express Integrated Graphics Controller [8086:27a2] (rev 03) (prog-if 00 [VGA controller])
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Interrupt: pin A routed to IRQ 16
	Region 0: Memory at d0300000 (32-bit, non-prefetchable) [size=512K]
	Region 1: I/O ports at 5088 [size=8]
	Region 2: Memory at c0000000 (32-bit, prefetchable) [size=256M]
	Region 3: Memory at d0400000 (32-bit, non-prefetchable) [size=256K]
	Capabilities: <access denied>
	Kernel modules: intelfb

00:02.1 Display controller [0380]: Intel Corporation Mobile 945GM/GMS/GME, 943/940GML Express Integrated Graphics Controller [8086:27a6] (rev 03)
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Region 0: Memory at d0380000 (32-bit, non-prefetchable) [size=512K]
	Capabilities: <access denied>

00:1b.0 Audio device [0403]: Intel Corporation 82801G (ICH7 Family) High Definition Audio Controller [8086:27d8] (rev 02)
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0, Cache Line Size: 64 bytes
	Interrupt: pin A routed to IRQ 22
	Region 0: Memory at d0440000 (64-bit, non-prefetchable) [size=16K]
	Capabilities: <access denied>
	Kernel driver in use: HDA Intel
	Kernel modules: snd-hda-intel

00:1c.0 PCI bridge [0604]: Intel Corporation 82801G (ICH7 Family) PCI Express Port 1 [8086:27d0] (rev 02) (prog-if 00 [Normal decode])
	Control: I/O- Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0, Cache Line Size: 64 bytes
	Bus: primary=00, secondary=02, subordinate=03, sec-latency=0
	Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort+ <SERR- <PERR-
	BridgeCtl: Parity- SERR- NoISA+ VGA- MAbort- >Reset- FastB2B-
		PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
	Capabilities: <access denied>
	Kernel driver in use: pcieport-driver
	Kernel modules: shpchp

00:1c.2 PCI bridge [0604]: Intel Corporation 82801G (ICH7 Family) PCI Express Port 3 [8086:27d4] (rev 02) (prog-if 00 [Normal decode])
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0, Cache Line Size: 64 bytes
	Bus: primary=00, secondary=04, subordinate=04, sec-latency=0
	Memory behind bridge: d0000000-d00fffff
	Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort+ <SERR- <PERR-
	BridgeCtl: Parity- SERR- NoISA+ VGA- MAbort- >Reset- FastB2B-
		PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
	Capabilities: <access denied>
	Kernel driver in use: pcieport-driver
	Kernel modules: shpchp

00:1c.3 PCI bridge [0604]: Intel Corporation 82801G (ICH7 Family) PCI Express Port 4 [8086:27d6] (rev 02) (prog-if 00 [Normal decode])
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0, Cache Line Size: 64 bytes
	Bus: primary=00, secondary=05, subordinate=05, sec-latency=0
	Memory behind bridge: d0100000-d01fffff
	Secondary status: 66MHz- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort+ <SERR- <PERR-
	BridgeCtl: Parity- SERR- NoISA+ VGA- MAbort- >Reset- FastB2B-
		PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
	Capabilities: <access denied>
	Kernel driver in use: pcieport-driver
	Kernel modules: shpchp

00:1d.0 USB Controller [0c03]: Intel Corporation 82801G (ICH7 Family) USB UHCI Controller #1 [8086:27c8] (rev 02) (prog-if 00 [UHCI])
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Interrupt: pin A routed to IRQ 23
	Region 4: I/O ports at 50a0 [size=32]
	Kernel driver in use: uhci_hcd
	Kernel modules: uhci-hcd

00:1d.1 USB Controller [0c03]: Intel Corporation 82801G (ICH7 Family) USB UHCI Controller #2 [8086:27c9] (rev 02) (prog-if 00 [UHCI])
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Interrupt: pin B routed to IRQ 19
	Region 4: I/O ports at 50c0 [size=32]
	Kernel driver in use: uhci_hcd
	Kernel modules: uhci-hcd

00:1d.2 USB Controller [0c03]: Intel Corporation 82801G (ICH7 Family) USB UHCI Controller #3 [8086:27ca] (rev 02) (prog-if 00 [UHCI])
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Interrupt: pin C routed to IRQ 18
	Region 4: I/O ports at 50e0 [size=32]
	Kernel driver in use: uhci_hcd
	Kernel modules: uhci-hcd

00:1d.3 USB Controller [0c03]: Intel Corporation 82801G (ICH7 Family) USB UHCI Controller #4 [8086:27cb] (rev 02) (prog-if 00 [UHCI])
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Interrupt: pin D routed to IRQ 16
	Region 4: I/O ports at 5400 [size=32]
	Kernel driver in use: uhci_hcd
	Kernel modules: uhci-hcd

00:1d.7 USB Controller [0c03]: Intel Corporation 82801G (ICH7 Family) USB2 EHCI Controller [8086:27cc] (rev 02) (prog-if 20 [EHCI])
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Interrupt: pin A routed to IRQ 23
	Region 0: Memory at d0644000 (32-bit, non-prefetchable) [size=1K]
	Capabilities: <access denied>
	Kernel driver in use: ehci_hcd
	Kernel modules: ehci-hcd

00:1e.0 PCI bridge [0604]: Intel Corporation 82801 Mobile PCI Bridge [8086:2448] (rev e2) (prog-if 01 [Subtractive decode])
	Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Bus: primary=00, secondary=06, subordinate=06, sec-latency=32
	Memory behind bridge: d0200000-d02fffff
	Secondary status: 66MHz- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort+ <SERR- <PERR-
	BridgeCtl: Parity- SERR- NoISA- VGA- MAbort- >Reset- FastB2B-
		PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
	Capabilities: <access denied>

00:1f.0 ISA bridge [0601]: Intel Corporation 82801GBM (ICH7-M) LPC Interface Bridge [8086:27b9] (rev 02)
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Capabilities: <access denied>
	Kernel modules: iTCO_wdt, intel-rng

00:1f.1 IDE interface [0101]: Intel Corporation 82801G (ICH7 Family) IDE Controller [8086:27df] (rev 02) (prog-if 8a [Master SecP PriP])
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O+ Mem- BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Interrupt: pin B routed to IRQ 19
	Region 0: I/O ports at 01f0 [size=8]
	Region 1: I/O ports at 03f4 [size=1]
	Region 2: I/O ports at 0170 [size=8]
	Region 3: I/O ports at 0374 [size=1]
	Region 4: I/O ports at 5090 [size=16]
	Kernel driver in use: PIIX_IDE
	Kernel modules: piix

00:1f.2 SATA controller [0106]: Intel Corporation 82801GBM/GHM (ICH7 Family) SATA AHCI Controller [8086:27c5] (rev 02) (prog-if 01 [AHCI 1.0])
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx+
	Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Interrupt: pin B routed to IRQ 220
	Region 0: I/O ports at 5478 [size=8]
	Region 1: I/O ports at 5470 [size=4]
	Region 2: I/O ports at 5468 [size=8]
	Region 3: I/O ports at 545c [size=4]
	Region 4: I/O ports at 5440 [size=16]
	Region 5: Memory at d0644400 (32-bit, non-prefetchable) [size=1K]
	Capabilities: <access denied>
	Kernel driver in use: ahci
	Kernel modules: ahci

00:1f.3 SMBus [0c05]: Intel Corporation 82801G (ICH7 Family) SMBus Controller [8086:27da] (rev 02)
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O+ Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Interrupt: pin B routed to IRQ 19
	Region 4: I/O ports at 5420 [size=32]
	Kernel driver in use: i801_smbus
	Kernel modules: i2c-i801

04:00.0 Ethernet controller [0200]: Broadcom Corporation NetLink BCM5787M Gigabit Ethernet PCI Express [14e4:1693] (rev 02)
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR+ <PERR- INTx-
	Latency: 0, Cache Line Size: 64 bytes
	Interrupt: pin A routed to IRQ 219
	Region 0: Memory at d0000000 (64-bit, non-prefetchable) [size=64K]
	Expansion ROM at <ignored> [disabled]
	Capabilities: <access denied>
	Kernel driver in use: tg3
	Kernel modules: tg3

05:00.0 Network controller [0280]: Broadcom Corporation BCM94311MCG wlan mini-PCI [14e4:4311] (rev 01)
	Subsystem: AMBIT Microsystem Corp. Unknown device [1468:0422]
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0, Cache Line Size: 64 bytes
	Interrupt: pin A routed to IRQ 19
	Region 0: Memory at d0100000 (32-bit, non-prefetchable) [size=16K]
	Capabilities: <access denied>
	Kernel driver in use: b43-pci-bridge
	Kernel modules: ssb

06:00.0 FLASH memory [0501]: ENE Technology Inc ENE PCI Memory Stick Card Reader Controller [1524:0730]
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 64 (250ns min, 1000ns max), Cache Line Size: 32 bytes
	Interrupt: pin A routed to IRQ 10
	Region 0: Memory at d0200000 (32-bit, non-prefetchable) [size=128]
	Capabilities: <access denied>

06:00.1 SD Host controller [0805]: ENE Technology Inc ENE PCI SmartMedia / xD Card Reader Controller [1524:0750] (prog-if 01)
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 64 (8000ns min, 18000ns max)
	Interrupt: pin A routed to IRQ 20
	Region 0: Memory at d0200400 (32-bit, non-prefetchable) [size=256]
	Capabilities: <access denied>
	Kernel driver in use: sdhci
	Kernel modules: sdhci

06:00.2 FLASH memory [0501]: ENE Technology Inc Unknown device [1524:0720]
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O- Mem- BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Interrupt: pin A routed to IRQ 10
	Region 0: Memory at d0200800 (32-bit, non-prefetchable) [disabled] [size=128]
	Capabilities: <access denied>

06:00.3 FLASH memory [0501]: ENE Technology Inc ENE PCI Secure Digital / MMC Card Reader Controller [1524:0751]
	Subsystem: Acer Incorporated [ALI] Unknown device [1025:012e]
	Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR- FastB2B- DisINTx-
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 64 (8000ns min, 18000ns max)
	Interrupt: pin A routed to IRQ 20
	Region 0: Memory at d0200100 (32-bit, non-prefetchable) [size=256]
	Capabilities: <access denied>
	Kernel driver in use: sdhci
	Kernel modules: sdhci



-- System Information:
Debian Release: lenny/sid
  APT prefers testing
  APT policy: (500, 'testing')
Architecture: i386 (i686)

Kernel: Linux 2.6.25-1-686 (SMP w/2 CPU cores)
Locale: LANG=en_US.UTF-8, LC_CTYPE=en_US.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/bash

Versions of packages linux-image-2.6.25-1-686 depends on:
ii  debconf [debconf-2.0]         1.5.21     Debian configuration management sy
ii  initramfs-tools [linux-initra 0.92a      tools for generating an initramfs
ii  module-init-tools             3.4-1      tools for managing Linux kernel mo

Versions of packages linux-image-2.6.25-1-686 recommends:
ii  libc6-i686                    2.7-10     GNU C Library: Shared libraries [i

-- debconf information:
  shared/kernel-image/really-run-bootloader: true
  linux-image-2.6.25-1-686/postinst/kimage-is-a-directory:
  linux-image-2.6.25-1-686/preinst/abort-install-2.6.25-1-686:
  linux-image-2.6.25-1-686/preinst/failed-to-move-modules-2.6.25-1-686:
  linux-image-2.6.25-1-686/postinst/old-dir-initrd-link-2.6.25-1-686: true
  linux-image-2.6.25-1-686/postinst/create-kimage-link-2.6.25-1-686: true
  linux-image-2.6.25-1-686/postinst/bootloader-test-error-2.6.25-1-686:
  linux-image-2.6.25-1-686/preinst/overwriting-modules-2.6.25-1-686: true
  linux-image-2.6.25-1-686/preinst/initrd-2.6.25-1-686:
  linux-image-2.6.25-1-686/postinst/old-initrd-link-2.6.25-1-686: true
  linux-image-2.6.25-1-686/postinst/depmod-error-2.6.25-1-686: false
  linux-image-2.6.25-1-686/preinst/lilo-has-ramdisk:
  linux-image-2.6.25-1-686/prerm/removing-running-kernel-2.6.25-1-686: true
  linux-image-2.6.25-1-686/postinst/bootloader-error-2.6.25-1-686:
  linux-image-2.6.25-1-686/postinst/depmod-error-initrd-2.6.25-1-686: false
  linux-image-2.6.25-1-686/preinst/abort-overwrite-2.6.25-1-686:
  linux-image-2.6.25-1-686/preinst/lilo-initrd-2.6.25-1-686: true
  linux-image-2.6.25-1-686/preinst/bootloader-initrd-2.6.25-1-686: true
  linux-image-2.6.25-1-686/preinst/elilo-initrd-2.6.25-1-686: true
  linux-image-2.6.25-1-686/prerm/would-invalidate-boot-loader-2.6.25-1-686: true
  linux-image-2.6.25-1-686/postinst/old-system-map-link-2.6.25-1-686: true
Comment 1 Sebastian Krause 2008-05-16 14:35:24 UTC
Created attachment 16167 [details]
My kernel configuration file for 2.6.26-rc2
Comment 2 Andrew Morton 2008-05-16 14:49:13 UTC
Maybe.  Please add initcall_debug to your kernel boot command line
and check that the pause is really occurring in 
iTCO_wdt_init_module, thanks.
Comment 3 Marco Piazza 2008-10-30 00:39:24 UTC
Same laptop (only wifi different Atheros AR5007EG instead of Broadcom Corporation BCM94311MCG wlan), same problem.

Tried all version from 2.6.25 on, til 2.6.28-rc2 and boot takes too long.
Tried also compiling all module inside kernel, with no difference in boot time.
Udev takes almost 30 seconds loading all modules.

These are the most expensive call from initcall_debug:
[    3.419058] initcall floppy_init+0x0/0xdab returned -19 after 2939983 usecs
[    4.594332] initcall ahci_init+0x0/0x16 returned 0 after 1144634 usecs
[   35.733602] initcall init_ath_pci+0x0/0x2d [ath_pci] returned 0 after 517674 usecs
[   46.038556] initcall acpi_ac_init+0x0/0x25 [ac] returned 0 after 374791 usecs
[   46.023236] initcall acpi_battery_init+0x0/0x25 [battery] returned 0 after 361114 usecs
[   24.721781] initcall alsa_seq_oss_init+0x0/0x147 [snd_seq_oss] returned 0 after 214003 usecs
[    4.772833] initcall piix_init+0x0/0x27 returned 0 after 174291 usecs
[   35.493628] initcall acpi_thermal_init+0x0/0x76 [thermal] returned 0 after 105574 usecs
[    0.191071] initcall acpi_scan_init+0x0/0xeb returned 0 after 77136 usecs
[   24.475255] initcall acer_wmi_init+0x0/0x462 [acer_wmi] returned 0 after 59278 usecs
[   45.695262] initcall acpi_video_init+0x0/0x51 [video] returned 0 after 56208 usecs
[   24.682026] initcall alsa_card_azx_init+0x0/0x19 [snd_hda_intel] returned 0 after 53435 usecs
[   35.162286] initcall acpi_button_init+0x0/0x51 [button] returned 0 after 38192 usecs
[    0.294363] initcall pnpacpi_init+0x0/0x7b returned 0 after 37460 usecs
[    0.403525] initcall pty_init+0x0/0x3e4 returned 0 after 34980 usecs
[    0.250422] initcall pcie_aspm_init+0x0/0x1f returned 0 after 34621 usecs
[    0.329966] initcall init_acpi_pm_clocksource+0x0/0x195 returned 0 after 33745 usecs
[   35.138390] initcall tg3_init+0x0/0x19 [tg3] returned 0 after 32958 usecs
[    4.798053] initcall i8042_init+0x0/0x345 returned 0 after 24583 usecs
[    0.355062] initcall inet_init+0x0/0x1c3 returned 0 after 23586 usecs
[   24.332571] initcall ehci_hcd_init+0x0/0x68 [ehci_hcd] returned 0 after 13934 usecs
[    4.810380] initcall pcspkr_init+0x0/0xf returned 0 after 10996 usecs
[    0.200017] initcall acpi_pci_root_init+0x0/0x25 returned 0 after 8787 usecs
[    0.112309] initcall acpi_init+0x0/0x232 returned 0 after 7811 usecs
[   35.285926] initcall acpi_processor_init+0x0/0xf0 [processor] returned 0 after 6969 usecs
[    0.214124] initcall cfg80211_init+0x0/0x53 returned 0 after 5858 usecs
[    0.255978] initcall acpi_event_init+0x0/0x46 returned 0 after 5399 usecs
[    0.207981] initcall genl_init+0x0/0xb0 returned 0 after 4882 usecs
[   24.395660] initcall uhci_hcd_init+0x0/0x9d [uhci_hcd] returned 0 after 3513 usecs
[    0.407553] initcall agp_intel_init+0x0/0x24 returned 0 after 3352 usecs
[    0.367676] initcall tty_init+0x0/0xd5 returned 0 after 3313 usecs
[    0.104202] initcall param_sysfs_init+0x0/0x184 returned 0 after 2929 usecs
[    0.360280] initcall slab_sysfs_init+0x0/0xb7 returned 0 after 2557 usecs
[    3.420724] initcall brd_init+0x0/0x14e returned 0 after 1602 usecs
[   89.557573] initcall inet6_init+0x0/0x25a [ipv6] returned 0 after 1343 usecs
[    0.214970] initcall hpet_late_init+0x0/0x10e returned 0 after 976 usecs
[    0.201974] initcall acpi_power_init+0x0/0x69 returned 0 after 976 usecs
[    0.201874] initcall acpi_pci_link_init+0x0/0x43 returned 0 after 976 usecs
[    0.101015] initcall vtconsole_class_init+0x0/0xa1 returned 0 after 976 usecs
[   83.154073] initcall fuse_init+0x0/0xfd [fuse] returned 0 after 947 usecs
[    0.364014] initcall pcie_portdrv_init+0x0/0x41 returned 0 after 895 usecs
[    0.330874] initcall pcibios_assign_resources+0x0/0x76 returned 0 after 860 usecs
[    3.421609] initcall loop_init+0x0/0x166 returned 0 after 840 usecs
[    4.799094] initcall psmouse_init+0x0/0x61 returned 0 after 626 usecs
[   95.346736] initcall i915_init+0x0/0x19 [i915] returned 0 after 606 usecs
[    0.408156] initcall serial8250_init+0x0/0xf2 returned 0 after 561 usecs
[   83.882469] initcall acpi_cpufreq_init+0x0/0x55 [acpi_cpufreq] returned 0 after 510 usecs
Comment 4 Marco Piazza 2008-11-02 23:31:40 UTC
I solved the problem.

It was due to udev and something related to libccid. 
I had on my sytem a rules file for udev called /etc/udev/rules.d/z60_libccid.rules.
Udev was trying to use always this rule for my input devices, taking a lot of time waiting for a 'bInterfaceProtocol' that at the end timed out.

Sorry for the noise!
Comment 5 anthony tippett 2009-03-03 21:42:27 UTC
I seem to have the same issue on a thinkpad t60p 

the solution is to remove that rule file above?
Comment 6 anthony tippett 2009-03-03 22:35:47 UTC
I resolved my issue by commenting out the eth1 line in /etc/udev/rules.d/70-persistent-net.rules

removed 30 seconds of boot time.

Note You need to log in before you can comment on or make changes to this bug.