Bug#607574: linux-image-2.6.32-5-686: wastes > 8s of boot time on something USB related that fails
Package: linux-2.6
Version: 2.6.32-29
Severity: normal
[ 16.409864] /build/buildd-linux-2.6_2.6.32-29-i386-Of6Yt1/linux-2.6-2.6.32/debian/build/source_i386_none/drivers/hid/usbhid/hid-core.c: usb_submit_urb(ctrl) failed
note the time on the previous log messages
-- Package-specific info:
** Version:
Linux version 2.6.32-5-686 (Debian 2.6.32-29) (ben@decadent.org.uk) (gcc version 4.3.5 (Debian 4.3.5-4) ) #1 SMP Fri Dec 10 16:12:40 UTC 2010
** Command line:
BOOT_IMAGE=/boot/vmlinuz-2.6.32-5-686 root=UUID- ro quiet
** Not tainted
** Kernel log:
[ 2.201885] scsi2 : SCSI emulation for USB Mass Storage devices
[ 2.202131] usbcore: registered new interface driver usb-storage
[ 2.202136] USB Mass Storage support registered.
[ 2.202394] usb-storage: device found at 2
[ 2.202398] usb-storage: waiting for device to settle before scanning
[ 2.312330] ata1.00: n_sectors mismatch 39062500 != 39102336
[ 2.312335] ata1.00: new n_sectors matches native, probably late HPA unlock, n_sectors updated
[ 2.329309] ata1.00: configured for UDMA/100
[ 2.329320] ata1: EH complete
[ 2.329663] sd 0:0:0:0: [sda] 39102336 512-byte logical blocks: (20.0 GB/18.6 GiB)
[ 2.329933] sda: detected capacity change from 20000000000 to 20020396032
[ 2.329942] sda: sda1 sda2
[ 2.372787] sd 0:0:0:0: [sda] Attached SCSI disk
[ 2.432691] sr0: scsi3-mmc drive: 24x/24x cd/rw xa/form2 cdda tray
[ 2.432699] Uniform CD-ROM driver Revision: 3.20
[ 2.432895] sr 1:0:0:0: Attached scsi CD-ROM sr0
[ 2.447078] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 2.448100] sr 1:0:0:0: Attached scsi generic sg1 type 5
[ 2.624034] usb 4-1: new low speed USB device using uhci_hcd and address 2
[ 2.682281] PM: Starting manual resume from disk
[ 2.682289] PM: Resume from partition 8:2
[ 2.682292] PM: Checking hibernation image.
[ 2.682543] PM: Error -22 checking image file
[ 2.682547] PM: Resume from disk failed.
[ 2.724026] kjournald starting. Commit interval 5 seconds
[ 2.724046] EXT3-fs: mounted filesystem with ordered data mode.
[ 2.840323] usb 4-1: New USB device found, idVendor=1c4f, idProduct=0002
[ 2.840330] usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 2.840336] usb 4-1: Product: USB Keykoard
[ 2.840340] usb 4-1: Manufacturer: USB
[ 2.840525] usb 4-1: configuration #1 chosen from 1 choice
[ 3.080022] usb 4-2: new low speed USB device using uhci_hcd and address 3
[ 3.255274] usb 4-2: New USB device found, idVendor=046d, idProduct=c016
[ 3.255281] usb 4-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 3.255286] usb 4-2: Product: Optical USB Mouse
[ 3.255290] usb 4-2: Manufacturer: Logitech
[ 3.255461] usb 4-2: configuration #1 chosen from 1 choice
[ 5.037672] udev[240]: starting version 164
[ 5.565664] input: PC Speaker as /devices/platform/pcspkr/input/input0
[ 5.642637] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[ 5.751548] processor LNXCPU:00: registered as cooling_device0
[ 5.855502] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input1
[ 5.855526] ACPI: Power Button [VBTN]
[ 5.855803] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
[ 5.855812] ACPI: Power Button [PWRF]
[ 5.862743] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[ 5.914946] intel_rng: FWH not detected
[ 5.987405] parport_pc 00:08: reported by Plug and Play ACPI
[ 5.987463] parport0: PC-style at 0x378 (0x778), irq 7 [PCSPP,TRISTATE]
[ 6.252700] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
[ 6.381631] usbcore: registered new interface driver hiddev
[ 6.396638] input: USB USB Keykoard as /devices/pci0000:00/0000:00:1d.2/usb4/4-1/4-1:1.0/input/input3
[ 6.397771] generic-usb 0003:1C4F:0002.0001: input,hidraw0: USB HID v1.10 Keyboard [USB USB Keykoard] on usb-0000:00:1d.2-1/input0
[ 6.685861] i801_smbus 0000:00:1f.3: PCI INT B -> GSI 17 (level, low) -> IRQ 17
[ 6.760622] [drm] Initialized drm 1.1.0 20060810
[ 6.987208] Error: Driver 'pcspkr' is already registered, aborting...
[ 7.073480] Intel ICH 0000:00:1f.5: PCI INT B -> GSI 17 (level, low) -> IRQ 17
[ 7.073551] Intel ICH 0000:00:1f.5: setting latency timer to 64
[ 7.200263] usb-storage: device scan complete
[ 7.200863] scsi 2:0:0:0: Direct-Access SAMSUNG HD103UI PQ: 0 ANSI: 2 CCS
[ 7.202652] sd 2:0:0:0: Attached scsi generic sg2 type 0
[ 7.203477] sd 2:0:0:0: [sdb] 1953525168 512-byte logical blocks: (1.00 TB/931 GiB)
[ 7.204231] sd 2:0:0:0: [sdb] Write Protect is off
[ 7.204239] sd 2:0:0:0: [sdb] Mode Sense: 28 00 00 00
[ 7.204244] sd 2:0:0:0: [sdb] Assuming drive cache: write through
[ 7.205846] sd 2:0:0:0: [sdb] Assuming drive cache: write through
[ 7.205914] sdb: sdb1 sdb2
[ 7.208832] sd 2:0:0:0: [sdb] Assuming drive cache: write through
[ 7.208900] sd 2:0:0:0: [sdb] Attached SCSI disk
[ 7.444032] intel8x0_measure_ac97_clock: measured 52535 usecs (2531 samples)
[ 7.444049] intel8x0: clocking to 48000
[ 7.445739] i915 0000:00:02.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[ 7.445750] i915 0000:00:02.0: setting latency timer to 64
[ 7.512122] [drm] set up 7M of stolen space
[ 7.550673] [drm] initialized overlay support
[ 7.793973] Console: switching to colour frame buffer device 200x75
[ 7.808040] fb0: inteldrmfb frame buffer device
[ 7.808044] registered panic notifier
[ 7.808651] [drm] Initialized i915 1.6.0 20080730 for 0000:00:02.0 on minor 0
[ 16.409864] /build/buildd-linux-2.6_2.6.32-29-i386-Of6Yt1/linux-2.6-2.6.32/debian/build/source_i386_none/drivers/hid/usbhid/hid-core.c: usb_submit_urb(ctrl) failed
[ 16.410069] generic-usb 0003:1C4F:0002.0002: timeout initializing reports
[ 16.410324] input: USB USB Keykoard as /devices/pci0000:00/0000:00:1d.2/usb4/4-1/4-1:1.1/input/input4
[ 16.410495] generic-usb 0003:1C4F:0002.0002: input,hidraw1: USB HID v1.10 Device [USB USB Keykoard] on usb-0000:00:1d.2-1/input1
[ 16.423820] input: Logitech Optical USB Mouse as /devices/pci0000:00/0000:00:1d.2/usb4/4-2/4-2:1.0/input/input5
[ 16.425897] generic-usb 0003:046D:C016.0003: input,hidraw2: USB HID v1.10 Mouse [Logitech Optical USB Mouse] on usb-0000:00:1d.2-2/input0
[ 16.425963] usbcore: registered new interface driver usbhid
[ 16.426319] usbhid: v2.6:USB HID core driver
[ 17.466027] Adding 506036k swap on /dev/sda2. Priority:-1 extents:1 across:506036k
[ 17.788779] EXT3 FS on sda1, internal journal
[ 18.012124] loop: module loaded
[ 20.963874] ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 20.964404] e1000: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: RX
[ 20.964826] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 21.491912] PPP generic driver version 2.4.2
[ 21.850906] NET: Registered protocol family 24
[ 23.380200] usbcore: registered new interface driver usblp
[ 24.276978] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 30.196308] lp0: using parport0 (interrupt-driven).
[ 30.270603] ppdev: user-space parallel port driver
[ 31.016017] eth0: no IPv6 routers present
** Model information
not available
** Loaded modules:
Module Size Used by
ppdev 4058 0
lp 5570 0
xt_TCPMSS 2303 1
xt_tcpmss 1017 1
xt_tcpudp 1743 1
iptable_mangle 2325 1
ip_tables 7690 1 iptable_mangle
x_tables 8327 4 xt_TCPMSS,xt_tcpmss,xt_tcpudp,ip_tables
usblp 7899 0
pppoe 6849 2
pppox 1242 1 pppoe
ppp_generic 16359 6 pppoe,pppox
slhc 3691 1 ppp_generic
loop 9765 0
joydev 6739 0
i915 222064 2
snd_intel8x0 19595 2
snd_ac97_codec 79148 1 snd_intel8x0
ac97_bus 710 1 snd_ac97_codec
drm_kms_helper 18301 1 i915
snd_pcm_oss 28671 0
snd_mixer_oss 10461 1 snd_pcm_oss
snd_pcm 47222 3 snd_intel8x0,snd_ac97_codec,snd_pcm_oss
drm 112088 3 i915,drm_kms_helper
i2c_i801 6462 0
i2c_algo_bit 3497 1 i915
snd_timer 12258 1 snd_pcm
usbhid 28008 0
snd 34375 10 snd_intel8x0,snd_ac97_codec,snd_pcm_oss,snd_mixer_oss,snd_pcm,snd_timer
dcdbas 3892 0
i2c_core 12696 5 i915,drm_kms_helper,drm,i2c_i801,i2c_algo_bit
hid 50873 1 usbhid
soundcore 3450 1 snd
snd_page_alloc 5045 2 snd_intel8x0,snd_pcm
parport_pc 15799 1
parport 22554 3 ppdev,lp,parport_pc
evdev 5609 9
video 14605 1 i915
shpchp 21220 0
button 3598 1 i915
psmouse 44657 0
output 1204 1 video
rng_core 2178 0
processor 26327 0
pci_hotplug 18065 1 shpchp
serio_raw 2916 0
pcspkr 1207 0
ext3 94204 1
jbd 32169 1 ext3
mbcache 3762 1 ext3
sg 15968 0
sr_mod 10770 0
usb_storage 30541 0
cdrom 26487 1 sr_mod
sd_mod 25969 3
crc_t10dif 1012 1 sd_mod
ata_generic 2067 0
ata_piix 17736 2
uhci_hcd 16057 0
thermal 9206 0
libata 115753 2 ata_generic,ata_piix
e1000 77317 0
floppy 40923 0
ehci_hcd 27851 0
thermal_sys 9378 3 video,processor,thermal
scsi_mod 101421 5 sg,sr_mod,usb_storage,sd_mod,libata
usbcore 98453 6 usblp,usbhid,usb_storage,uhci_hcd,ehci_hcd
nls_base 4541 1 usbcore
** PCI devices:
00:00.0 Host bridge [0600]: Intel Corporation 82845G/GL[Brookdale-G]/GE/PE DRAM Controller/Host-Hub Interface [8086:2560] (rev 01)
Subsystem: Dell Optiplex GX260 [1028:0126]
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 e8000000 (32-bit, prefetchable) [size=128M]
Capabilities: <access denied>
Kernel driver in use: agpgart-intel
00:02.0 VGA compatible controller [0300]: Intel Corporation 82845G/GL[Brookdale-G]/GE Chipset Integrated Graphics Device [8086:2562] (rev 01) (prog-if 00 [VGA controller])
Subsystem: Dell Device [1028:0126]
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 f0000000 (32-bit, prefetchable) [size=128M]
Region 1: Memory at ff680000 (32-bit, non-prefetchable) [size=512K]
Expansion ROM at <unassigned> [disabled]
Capabilities: <access denied>
Kernel driver in use: i915
00:1d.0 USB Controller [0c03]: Intel Corporation 82801DB/DBL/DBM (ICH4/ICH4-L/ICH4-M) USB UHCI Controller #1 [8086:24c2] (rev 01) (prog-if 00 [UHCI])
Subsystem: Dell Optiplex GX260 [1028:0126]
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 16
Region 4: I/O ports at ff80 [size=32]
Kernel driver in use: uhci_hcd
00:1d.1 USB Controller [0c03]: Intel Corporation 82801DB/DBL/DBM (ICH4/ICH4-L/ICH4-M) USB UHCI Controller #2 [8086:24c4] (rev 01) (prog-if 00 [UHCI])
Subsystem: Dell Optiplex GX260 [1028:0126]
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 ff60 [size=32]
Kernel driver in use: uhci_hcd
00:1d.2 USB Controller [0c03]: Intel Corporation 82801DB/DBL/DBM (ICH4/ICH4-L/ICH4-M) USB UHCI Controller #3 [8086:24c7] (rev 01) (prog-if 00 [UHCI])
Subsystem: Dell Optiplex GX260 [1028:0126]
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 ff40 [size=32]
Kernel driver in use: uhci_hcd
00:1d.7 USB Controller [0c03]: Intel Corporation 82801DB/DBM (ICH4/ICH4-M) USB2 EHCI Controller [8086:24cd] (rev 01) (prog-if 20 [EHCI])
Subsystem: Dell Optiplex GX260 [1028:0126]
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 23
Region 0: Memory at ffa00800 (32-bit, non-prefetchable) [size=1K]
Capabilities: <access denied>
Kernel driver in use: ehci_hcd
00:1e.0 PCI bridge [0604]: Intel Corporation 82801 PCI Bridge [8086:244e] (rev 81) (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
Bus: primary=00, secondary=01, subordinate=01, sec-latency=32
I/O behind bridge: 0000e000-0000efff
Memory behind bridge: ff800000-ff9fffff
Secondary status: 66MHz- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- <SERR- <PERR-
BridgeCtl: Parity- SERR+ NoISA+ VGA- MAbort- >Reset- FastB2B-
PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
00:1f.0 ISA bridge [0601]: Intel Corporation 82801DB/DBL (ICH4/ICH4-L) LPC Interface Bridge [8086:24c0] (rev 01)
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
00:1f.1 IDE interface [0101]: Intel Corporation 82801DB (ICH4) IDE Controller [8086:24cb] (rev 01) (prog-if 8a [Master SecP PriP])
Subsystem: Dell Optiplex GX260 [1028:0126]
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 18
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 ffa0 [size=16]
Region 5: Memory at 40000000 (32-bit, non-prefetchable) [size=1K]
Kernel driver in use: ata_piix
00:1f.3 SMBus [0c05]: Intel Corporation 82801DB/DBL/DBM (ICH4/ICH4-L/ICH4-M) SMBus Controller [8086:24c3] (rev 01)
Subsystem: Dell Optiplex GX260 [1028:0126]
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 17
Region 4: I/O ports at dc80 [size=32]
Kernel driver in use: i801_smbus
00:1f.5 Multimedia audio controller [0401]: Intel Corporation 82801DB/DBL/DBM (ICH4/ICH4-L/ICH4-M) AC'97 Audio Controller [8086:24c5] (rev 01)
Subsystem: Dell Device [1028:0126]
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 17
Region 0: I/O ports at d800 [size=256]
Region 1: I/O ports at dc40 [size=64]
Region 2: Memory at ffa00400 (32-bit, non-prefetchable) [size=512]
Region 3: Memory at ffa00000 (32-bit, non-prefetchable) [size=256]
Capabilities: <access denied>
Kernel driver in use: Intel ICH
01:0c.0 Ethernet controller [0200]: Intel Corporation 82540EM Gigabit Ethernet Controller [8086:100e] (rev 02)
Subsystem: Dell Optiplex GX260 [1028:002e]
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 (63750ns min), Cache Line Size: 64 bytes
Interrupt: pin A routed to IRQ 18
Region 0: Memory at ff8e0000 (32-bit, non-prefetchable) [size=128K]
Region 2: I/O ports at ecc0 [size=64]
Capabilities: <access denied>
Kernel driver in use: e1000
** USB devices:
Bus 004 Device 003: ID 046d:c016 Logitech, Inc. Optical Wheel Mouse
Bus 004 Device 002: ID 1c4f:0002 SiGma Micro
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 002: ID 152d:2338 JMicron Technology Corp. / JMicron USA Technology Corp. JM20337 Hi-Speed USB to SATA & PATA Combo Bridge
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
-- System Information:
Debian Release: squeeze/sid
APT prefers testing
APT policy: (500, 'testing')
Architecture: i386 (i686)
Kernel: Linux 2.6.32-5-686 (SMP w/1 CPU core)
Locale: LANG=de_DE.UTF-8, LC_CTYPE=de_DE.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash
Versions of packages linux-image-2.6.32-5-686 depends on:
ii debconf [debconf-2.0] 1.5.36 Debian configuration management sy
ii initramfs-tools [linux-initra 0.98.5 tools for generating an initramfs
ii linux-base 2.6.32-29 Linux image base package
ii module-init-tools 3.12-1 tools for managing Linux kernel mo
Versions of packages linux-image-2.6.32-5-686 recommends:
ii firmware-linux-free 2.6.32-29 Binary firmware for various driver
ii libc6-i686 2.11.2-7 Embedded GNU C Library: Shared lib
Versions of packages linux-image-2.6.32-5-686 suggests:
ii grub 0.97-63 GRand Unified Bootloader (dummy pa
pn linux-doc-2.6.32 <none> (no description available)
Versions of packages linux-image-2.6.32-5-686 is related to:
pn firmware-bnx2 <none> (no description available)
pn firmware-bnx2x <none> (no description available)
pn firmware-ipw2x00 <none> (no description available)
pn firmware-ivtv <none> (no description available)
pn firmware-iwlwifi <none> (no description available)
pn firmware-linux <none> (no description available)
pn firmware-linux-nonfree <none> (no description available)
pn firmware-qlogic <none> (no description available)
pn firmware-ralink <none> (no description available)
pn xen-hypervisor <none> (no description available)
-- debconf information excluded
Reply to: