According to Alan Stern:
> On Thu, 19 Feb 2004, Chip Salzenberg wrote:
> > I just got a USB keyboard for use with my ThinkPad A30.  When I
> > plugged it in the first time, it worked great.  Then I unplugged it
> > for the night.  But the nefxt day when I plugged in the keyboard
> > again, the kernel broke.
> 
> Can you try running kernel 2.6.3, and configuring on the USB debugging 
> option?  Also, please post the output from lspci.

Still fails in 2.6.3.  Logs, with debugging on, are included below.

A clue: The failure is not caused by re-plugging per se.  It's
triggered by APM suspension.  When I tested my system today with
2.6.3, it worked OK at first; then I got the idea to suspend and
try again, and then it failed.

Output of lspci:

00:00.0 Host bridge: Intel Corp. 82830 830 Chipset Host Bridge (rev 02)
00:01.0 PCI bridge: Intel Corp. 82830 830 Chipset AGP Bridge (rev 02)
00:1d.0 USB Controller: Intel Corp. 82801CA/CAM USB (Hub #1) (rev 01)
00:1d.1 USB Controller: Intel Corp. 82801CA/CAM USB (Hub #2) (rev 01)
00:1d.2 USB Controller: Intel Corp. 82801CA/CAM USB (Hub #3) (rev 01)
00:1e.0 PCI bridge: Intel Corp. 82801BAM/CAM PCI Bridge (rev 41)
00:1f.0 ISA bridge: Intel Corp. 82801CAM ISA Bridge (LPC) (rev 01)
00:1f.1 IDE interface: Intel Corp. 82801CAM IDE U100 (rev 01)
00:1f.3 SMBus: Intel Corp. 82801CA/CAM SMBus Controller (rev 01)
00:1f.5 Multimedia audio controller: Intel Corp. 82801CA/CAM AC'97 Audio Controller 
(rev 01)
00:1f.6 Modem: Intel Corp. 82801CA/CAM AC'97 Modem Controller (rev 01)
01:00.0 VGA compatible controller: ATI Technologies Inc Radeon Mobility M6 LY
02:00.0 CardBus bridge: Ricoh Co Ltd RL5c476 II (rev 80)
02:00.1 CardBus bridge: Ricoh Co Ltd RL5c476 II (rev 80)
02:02.0 Network controller: Harris Semiconductor Prism 2.5 Wavelan chipset (rev 01)
02:08.0 Ethernet controller: Intel Corp. 82801CAM (ICH3) PRO/100 VE (LOM) Ethernet 
Controller (rev 41)

Key bits with lspci -v:

00:1d.0 USB Controller: Intel Corp. 82801CA/CAM USB (Hub #1) (rev 01) (prog-if 00 
[UHCI])
        Subsystem: IBM ThinkPad A/T/X Series
        Flags: bus master, medium devsel, latency 0, IRQ 11
        I/O ports at 1800 [size=32]

00:1d.1 USB Controller: Intel Corp. 82801CA/CAM USB (Hub #2) (rev 01) (prog-if 00 
[UHCI])
        Subsystem: IBM ThinkPad A/T/X Series
        Flags: bus master, medium devsel, latency 0, IRQ 9
        I/O ports at 1820 [size=32]

00:1d.2 USB Controller: Intel Corp. 82801CA/CAM USB (Hub #3) (rev 01) (prog-if 00 
[UHCI])
        Subsystem: IBM ThinkPad A/T/X Series
        Flags: bus master, medium devsel, latency 0, IRQ 9
        I/O ports at 1840 [size=32]

Log [warning, it's verbose]...

*** NORMAL OPERATION BEFORE SUSPEND:

16:04:37 drivers/usb/host/uhci-hcd.c: 1820: wakeup_hc
16:04:37 hub 2-0:1.0: port 1, status 101, change 1, 12 Mb/s
16:04:38 hub 2-0:1.0: debounce: port 1: delay 100ms stable 4 status 0x101
16:04:38 usb 2-1: new full speed USB device using address 2
16:04:38 usb 2-1: new device strings: Mfr=1, Product=2, SerialNumber=0
16:04:38 drivers/usb/core/message.c: USB device number 2 default language ID 0x409
16:04:38 usb 2-1: Product: USB 1.1 2 port downstream low-power hub
16:04:38 usb 2-1: Manufacturer: Lite-On Tech
16:04:38 drivers/usb/core/usb.c: usb_hotplug
16:04:38 usb 2-1: registering 2-1:1.0 (config #1, interface 0)
16:04:38 drivers/usb/core/usb.c: usb_hotplug
16:04:38 hub 2-1:1.0: usb_probe_interface
16:04:38 hub 2-1:1.0: usb_probe_interface - got id
16:04:38 hub 2-1:1.0: USB hub found
16:04:38 hub 2-1:1.0: 4 ports detected
16:04:38 hub 2-1:1.0: standalone hub
16:04:38 hub 2-1:1.0: individual port power switching
16:04:38 hub 2-1:1.0: individual port over-current protection
16:04:38 hub 2-1:1.0: Port indicators are not supported
16:04:38 hub 2-1:1.0: power on to power good time: 100ms
16:04:38 hub 2-1:1.0: hub controller current requirement: 20mA
16:04:38 hub 2-1:1.0: local power source is good
16:04:38 hub 2-1:1.0: no over-current condition exists
16:04:38 hub 2-1:1.0: enabling power on all ports
16:04:38 hub 2-1:1.0: port 3, status 301, change 1, 1.5 Mb/s
16:04:38 hub 2-1:1.0: debounce: port 3: delay 100ms stable 4 status 0x301
16:04:38 usb 2-1.3: new low speed USB device using address 3
16:04:39 usb 2-1.3: new device strings: Mfr=1, Product=2, SerialNumber=0
16:04:39 drivers/usb/core/message.c: USB device number 3 default language ID 0x409
16:04:39 usb 2-1.3: Product: IBM USB Keyboard with UltraNav
16:04:39 usb 2-1.3: Manufacturer: Lite-On Tech
16:04:39 drivers/usb/core/usb.c: usb_hotplug
16:04:39 usb 2-1.3: registering 2-1.3:1.0 (config #1, interface 0)
16:04:39 drivers/usb/core/usb.c: usb_hotplug
16:04:39 usb 2-1.3: registering 2-1.3:1.1 (config #1, interface 1)
16:04:39 drivers/usb/core/usb.c: usb_hotplug
16:04:39 hub 2-1:1.0: port 4, status 301, change 1, 1.5 Mb/s
16:04:39 drivers/usb/core/usb.c: registered new driver hiddev
16:04:39 hid 2-1.3:1.0: usb_probe_interface
16:04:39 hid 2-1.3:1.0: usb_probe_interface - got id
16:04:39 input: USB HID v1.10 Keyboard [Lite-On Tech IBM USB Keyboard with UltraNav] 
on usb-0000:00:1d.1-1.3
16:04:39 hid 2-1.3:1.1: usb_probe_interface
16:04:39 hid 2-1.3:1.1: usb_probe_interface - got id
16:04:39 hub 2-1:1.0: debounce: port 4: delay 100ms stable 4 status 0x301
16:04:39 input: USB HID v1.10 Device [Lite-On Tech IBM USB Keyboard with UltraNav] on 
usb-0000:00:1d.1-1.3
16:04:39 drivers/usb/core/usb.c: registered new driver hid
16:04:39 drivers/usb/input/hid-core.c: v2.0:USB HID core driver
16:04:39 usb 2-1.4: new low speed USB device using address 4
16:04:39 usb 2-1.4: new device strings: Mfr=1, Product=2, SerialNumber=0
16:04:39 drivers/usb/core/message.c: USB device number 4 default language ID 0x409
16:04:39 usb 2-1.4: Product: Composite TouchPad / TrackPoint
16:04:39 usb 2-1.4: Manufacturer: Synaptics Inc.
16:04:39 drivers/usb/core/usb.c: usb_hotplug
16:04:40 usb 2-1.4: registering 2-1.4:1.0 (config #1, interface 0)
16:04:40 drivers/usb/core/usb.c: usb_hotplug
16:04:40 hid 2-1.4:1.0: usb_probe_interface
16:04:40 hid 2-1.4:1.0: usb_probe_interface - got id
16:04:40 input: USB HID v1.00 Mouse [Synaptics Inc. Composite TouchPad / TrackPoint] 
on usb-0000:00:1d.1-1.4
16:04:40 usb 2-1.4: registering 2-1.4:1.1 (config #1, interface 1)
16:04:40 drivers/usb/core/usb.c: usb_hotplug
16:04:40 hid 2-1.4:1.1: usb_probe_interface
16:04:40 hid 2-1.4:1.1: usb_probe_interface - got id
16:04:40 input: USB HID v1.00 Mouse [Synaptics Inc. Composite TouchPad / TrackPoint] 
on usb-0000:00:1d.1-1.4
16:05:20 hub 2-0:1.0: port 1, status 100, change 3, 12 Mb/s
16:05:20 usb 2-1: USB disconnect, address 2
16:05:20 usb 2-1.3: USB disconnect, address 3
16:05:20 usb 2-1.3: usb_disable_device nuking all URBs
16:05:20 uhci_hcd 0000:00:1d.1: shutdown urb d7816620 pipe 40408380 ep1in-intr
16:05:20 uhci_hcd 0000:00:1d.1: shutdown urb d78164a0 pipe 40410380 ep2in-intr
16:05:20 usb 2-1.3: unregistering interface 2-1.3:1.0
16:05:20 usb 2-1.3: hcd_unlink_urb d7816620 fail -16
16:05:20 usb 2-1.3: hcd_unlink_urb d7816560 fail -22
16:05:20 usb 2-1.3: hcd_unlink_urb d7816620 fail -16
16:05:20 drivers/usb/core/usb.c: usb_hotplug
16:05:20 usb 2-1.3: unregistering interface 2-1.3:1.1
16:05:20 usb 2-1.3: hcd_unlink_urb d78164a0 fail -22
16:05:20 usb 2-1.3: hcd_unlink_urb d78163e0 fail -22
16:05:20 usb 2-1.3: hcd_unlink_urb d78164a0 fail -22
16:05:20 drivers/usb/core/usb.c: usb_hotplug
16:05:20 hub 2-1:1.0: transfer --> -84
16:05:20 usb 2-1.3: unregistering device
16:05:20 drivers/usb/core/usb.c: usb_hotplug
16:05:20 usb 2-1.4: USB disconnect, address 4
16:05:20 usb 2-1.4: usb_disable_device nuking all URBs
16:05:20 uhci_hcd 0000:00:1d.1: shutdown urb d78162c0 pipe 40408480 ep1in-intr
16:05:20 uhci_hcd 0000:00:1d.1: shutdown urb d7816140 pipe 40410480 ep2in-intr
16:05:20 usb 2-1.4: unregistering interface 2-1.4:1.0
16:05:20 usb 2-1.4: hcd_unlink_urb d78162c0 fail -16
16:05:20 usb 2-1.4: hcd_unlink_urb d7816260 fail -22
16:05:20 usb 2-1.4: hcd_unlink_urb d78162c0 fail -16
16:05:20 drivers/usb/core/usb.c: usb_hotplug
16:05:20 usb 2-1.4: unregistering interface 2-1.4:1.1
16:05:20 usb 2-1.4: hcd_unlink_urb d7816140 fail -22
16:05:20 usb 2-1.4: hcd_unlink_urb d78160e0 fail -22
16:05:20 usb 2-1.4: hcd_unlink_urb d7816140 fail -22
16:05:20 drivers/usb/core/usb.c: usb_hotplug
16:05:20 usb 2-1.4: unregistering device
16:05:20 drivers/usb/core/usb.c: usb_hotplug
16:05:20 usb 2-1: usb_disable_device nuking all URBs
16:05:20 usb 2-1: unregistering interface 2-1:1.0
16:05:20 usb 2-1: hcd_unlink_urb d7816680 fail -22
16:05:20 drivers/usb/core/usb.c: usb_hotplug
16:05:20 usb 2-1: unregistering device
16:05:20 drivers/usb/core/usb.c: usb_hotplug
16:05:20 hub 2-0:1.0: port 1 enable change, status 100
16:05:21 drivers/usb/host/uhci-hcd.c: 1820: suspend_hc
16:05:55 drivers/usb/host/uhci-hcd.c: 1820: wakeup_hc
16:05:55 hub 2-0:1.0: port 1, status 101, change 1, 12 Mb/s
16:05:55 hub 2-0:1.0: debounce: port 1: delay 100ms stable 4 status 0x101
16:05:55 usb 2-1: new full speed USB device using address 5
16:05:55 usb 2-1: new device strings: Mfr=1, Product=2, SerialNumber=0
16:05:55 drivers/usb/core/message.c: USB device number 5 default language ID 0x409
16:05:55 usb 2-1: Product: USB 1.1 2 port downstream low-power hub
16:05:55 usb 2-1: Manufacturer: Lite-On Tech
16:05:55 drivers/usb/core/usb.c: usb_hotplug
16:05:55 usb 2-1: registering 2-1:1.0 (config #1, interface 0)
16:05:55 drivers/usb/core/usb.c: usb_hotplug
16:05:55 hub 2-1:1.0: usb_probe_interface
16:05:55 hub 2-1:1.0: usb_probe_interface - got id
16:05:55 hub 2-1:1.0: USB hub found
16:05:55 hub 2-1:1.0: 4 ports detected
16:05:55 hub 2-1:1.0: standalone hub
16:05:55 hub 2-1:1.0: individual port power switching
16:05:55 hub 2-1:1.0: individual port over-current protection
16:05:55 hub 2-1:1.0: Port indicators are not supported
16:05:55 hub 2-1:1.0: power on to power good time: 100ms
16:05:55 hub 2-1:1.0: hub controller current requirement: 20mA
16:05:55 hub 2-1:1.0: local power source is good
16:05:55 hub 2-1:1.0: no over-current condition exists
16:05:55 hub 2-1:1.0: enabling power on all ports
16:05:55 hub 2-1:1.0: port 3, status 301, change 1, 1.5 Mb/s
16:05:55 hub 2-1:1.0: debounce: port 3: delay 100ms stable 4 status 0x301
16:05:56 usb 2-1.3: new low speed USB device using address 6
16:05:56 usb 2-1.3: new device strings: Mfr=1, Product=2, SerialNumber=0
16:05:56 drivers/usb/core/message.c: USB device number 6 default language ID 0x409
16:05:56 usb 2-1.3: Product: IBM USB Keyboard with UltraNav
16:05:56 usb 2-1.3: Manufacturer: Lite-On Tech
16:05:56 drivers/usb/core/usb.c: usb_hotplug
16:05:56 usb 2-1.3: registering 2-1.3:1.0 (config #1, interface 0)
16:05:56 drivers/usb/core/usb.c: usb_hotplug
16:05:56 hid 2-1.3:1.0: usb_probe_interface
16:05:56 hid 2-1.3:1.0: usb_probe_interface - got id
16:05:56 input: USB HID v1.10 Keyboard [Lite-On Tech IBM USB Keyboard with UltraNav] 
on usb-0000:00:1d.1-1.3
16:05:56 usb 2-1.3: registering 2-1.3:1.1 (config #1, interface 1)
16:05:56 drivers/usb/core/usb.c: usb_hotplug
16:05:56 hid 2-1.3:1.1: usb_probe_interface
16:05:56 hid 2-1.3:1.1: usb_probe_interface - got id
16:05:56 input: USB HID v1.10 Device [Lite-On Tech IBM USB Keyboard with UltraNav] on 
usb-0000:00:1d.1-1.3
16:05:56 hub 2-1:1.0: port 4, status 301, change 1, 1.5 Mb/s
16:05:57 hub 2-1:1.0: debounce: port 4: delay 100ms stable 4 status 0x301
16:05:57 usb 2-1.4: new low speed USB device using address 7
16:05:57 usb 2-1.4: new device strings: Mfr=1, Product=2, SerialNumber=0
16:05:57 drivers/usb/core/message.c: USB device number 7 default language ID 0x409
16:05:57 usb 2-1.4: Product: Composite TouchPad / TrackPoint
16:05:57 usb 2-1.4: Manufacturer: Synaptics Inc.
16:05:57 drivers/usb/core/usb.c: usb_hotplug
16:05:57 usb 2-1.4: registering 2-1.4:1.0 (config #1, interface 0)
16:05:57 drivers/usb/core/usb.c: usb_hotplug
16:05:57 hid 2-1.4:1.0: usb_probe_interface
16:05:57 hid 2-1.4:1.0: usb_probe_interface - got id
16:05:57 input: USB HID v1.00 Mouse [Synaptics Inc. Composite TouchPad / TrackPoint] 
on usb-0000:00:1d.1-1.4
16:05:57 usb 2-1.4: registering 2-1.4:1.1 (config #1, interface 1)
16:05:57 drivers/usb/core/usb.c: usb_hotplug
16:05:57 hid 2-1.4:1.1: usb_probe_interface
16:05:57 hid 2-1.4:1.1: usb_probe_interface - got id
16:05:58 input: USB HID v1.00 Mouse [Synaptics Inc. Composite TouchPad / TrackPoint] 
on usb-0000:00:1d.1-1.4

*** SUSPEND WITH APM:

16:06:29 hdc: start_power_step(step: 0)
16:06:29 hdc: completing PM request, suspend
16:06:29 hda: start_power_step(step: 0)
16:06:29 hda: start_power_step(step: 1)
16:06:29 hda: complete_power_step(step: 1, stat: 50, err: 0)
16:06:29 hda: completing PM request, suspend

*** RESUME FROM SUSPEND:

16:06:41 uhci_hcd 0000:00:1d.2: suspend D4 --> D3
16:06:41 drivers/usb/host/uhci-hcd.c: 1840: suspend_hc
16:06:41 uhci_hcd 0000:00:1d.1: suspend D4 --> D3
16:06:41 drivers/usb/host/uhci-hcd.c: 1820: suspend_hc
16:06:41 uhci_hcd 0000:00:1d.0: suspend D4 --> D3
16:06:41 drivers/usb/host/uhci-hcd.c: 1800: suspend_hc
16:06:41 uhci_hcd 0000:00:1d.0: resume from state D4
16:06:41 PCI: Setting latency timer of device 0000:00:1d.0 to 64
16:06:41 uhci_hcd 0000:00:1d.1: resume from state D4
16:06:41 PCI: Setting latency timer of device 0000:00:1d.1 to 64
16:06:41 uhci_hcd 0000:00:1d.2: resume from state D4
16:06:41 PCI: Setting latency timer of device 0000:00:1d.2 to 64
16:06:41 drivers/usb/host/uhci-hcd.c: 1800: wakeup_hc
16:06:41 drivers/usb/host/uhci-hcd.c: 1820: wakeup_hc
16:06:41 drivers/usb/host/uhci-hcd.c: 1840: wakeup_hc
16:06:41 hda: Wakeup request inited, waiting for !BSY...
16:06:41 hda: start_power_step(step: 1000)
16:06:41 blk: queue e7da9200, I/O limit 4095Mb (mask 0xffffffff)
16:06:41 hda: completing PM request, resume
16:06:41 hdc: Wakeup request inited, waiting for !BSY...
16:06:41 hdc: start_power_step(step: 1000)
16:06:41 hdc: completing PM request, resume
16:06:41 hub 2-0:1.0: port 1, status 101, change 1, 12 Mb/s
16:06:41 usb 2-1: USB disconnect, address 5
16:06:41 usb 2-1.3: USB disconnect, address 6
16:06:41 usb 2-1.3: usb_disable_device nuking all URBs
16:06:41 uhci_hcd 0000:00:1d.1: shutdown urb d78163e0 pipe 40408680 ep1in-intr
16:06:41 uhci_hcd 0000:00:1d.1: shutdown urb d78162c0 pipe 40410680 ep2in-intr
16:06:41 usb 2-1.3: unregistering interface 2-1.3:1.0
16:06:41 usb 2-1.3: hcd_unlink_urb d78163e0 fail -16
16:06:41 usb 2-1.3: hcd_unlink_urb d7816200 fail -22
16:06:41 usb 2-1.3: hcd_unlink_urb d78163e0 fail -16
16:06:41 drivers/usb/core/usb.c: usb_hotplug
16:06:41 usb 2-1.3: unregistering interface 2-1.3:1.1
16:06:41 usb 2-1.3: hcd_unlink_urb d78162c0 fail -16
16:06:41 usb 2-1.3: hcd_unlink_urb d7816140 fail -22
16:06:41 usb 2-1.3: hcd_unlink_urb d78162c0 fail -16
16:06:41 drivers/usb/core/usb.c: usb_hotplug
16:06:41 usb 2-1.3: unregistering device
16:06:41 drivers/usb/core/usb.c: usb_hotplug
16:06:41 usb 2-1.4: USB disconnect, address 7
16:06:41 usb 2-1.4: usb_disable_device nuking all URBs
16:06:41 uhci_hcd 0000:00:1d.1: shutdown urb d5b2e0e0 pipe 40408780 ep1in-intr
16:06:41 uhci_hcd 0000:00:1d.1: shutdown urb e13815a0 pipe 40410780 ep2in-intr
16:06:41 usb 2-1.4: unregistering interface 2-1.4:1.0
16:06:41 usb 2-1.4: hcd_unlink_urb d5b2e0e0 fail -16
16:06:41 usb 2-1.4: hcd_unlink_urb e13816c0 fail -22
16:06:41 usb 2-1.4: hcd_unlink_urb d5b2e0e0 fail -16
16:06:41 drivers/usb/core/usb.c: usb_hotplug
16:06:41 usb 2-1.4: unregistering interface 2-1.4:1.1
16:06:41 usb 2-1.4: hcd_unlink_urb e13815a0 fail -16
16:06:41 usb 2-1.4: hcd_unlink_urb e13817e0 fail -22
16:06:41 usb 2-1.4: hcd_unlink_urb e13815a0 fail -16
16:06:41 drivers/usb/core/usb.c: usb_hotplug
16:06:41 usb 2-1.4: unregistering device
16:06:41 drivers/usb/core/usb.c: usb_hotplug
16:06:41 usb 2-1: usb_disable_device nuking all URBs
16:06:41 uhci_hcd 0000:00:1d.1: shutdown urb d78164a0 pipe 40408580 ep1in-intr
16:06:41 usb 2-1: unregistering interface 2-1:1.0
16:06:41 usb 2-1: hcd_unlink_urb d78164a0 fail -16
16:06:44 e100: eth0 NIC Link is Up 100 Mbps Full duplex
16:06:44 drivers/usb/host/uhci-hcd.c: 1800: suspend_hc
16:06:44 drivers/usb/host/uhci-hcd.c: 1840: suspend_hc
16:06:45 PCI: Found IRQ 10 for device 0000:00:1f.5
16:06:45 PCI: Sharing IRQ 10 with 0000:00:1f.3
16:06:45 PCI: Sharing IRQ 10 with 0000:00:1f.6
16:06:45 PCI: Sharing IRQ 10 with 0000:02:00.1
16:06:45 PCI: Setting latency timer of device 0000:00:1f.5 to 64
16:06:46 intel8x0_measure_ac97_clock: measured 49455 usecs
16:06:46 intel8x0: clocking to 48000
16:07:13 drivers/usb/host/uhci-hcd.c: 1820: suspend_hc
16:08:00 uhci_hcd 0000:00:1d.0: remove, state 1
16:08:00 uhci_hcd 0000:00:1d.0: roothub graceful disconnect
16:08:00 usb usb1: USB disconnect, address 1
16:08:00 usb usb1: usb_disable_device nuking all URBs
16:08:00 uhci_hcd 0000:00:1d.0: shutdown urb e729fd20 pipe 40408180 ep1in-intr
16:08:00 usb usb1: unregistering interface 1-0:1.0

-- 
Chip Salzenberg               - a.k.a. -               <[EMAIL PROTECTED]>
"I wanted to play hopscotch with the impenetrable mystery of existence,
    but he stepped in a wormhole and had to go in early."  // MST3K


-------------------------------------------------------
SF.Net is sponsored by: Speed Start Your Linux Apps Now.
Build and deploy apps & Web services for Linux with
a free DVD software kit from IBM. Click Now!
http://ads.osdn.com/?ad_id=1356&alloc_id=3438&op=click
_______________________________________________
[EMAIL PROTECTED]
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-devel

Reply via email to