Dist: Debian unstable
Kernel: 2.6.1 stock
Machine: IBM T23 Laptop
The controller works fine with the Microsoft USB k/b and mouse I use
until the machine is suspended (by running apm --suspend or closing
the lid), but on resume it refuses to work. Trying to rmmod the module
results in it hanging at this point.
It works fine if I remember to rmmod the controller before suspending,
and I'd consider using this as a workaround, but it's not ideal.
I've attached the syslog from when the controller is loaded, when it's
suspended then resumed, and a trace from the rmmod that hangs.
Hopefully this will mean something to someone, if you need more info
just let me know.
Thanks,
Barnaby
Jan 29 17:20:47 purple kernel: drivers/usb/core/usb.c: registered new driver usbfs
Jan 29 17:20:47 purple kernel: drivers/usb/core/usb.c: registered new driver hub
Jan 29 17:20:47 purple kernel: drivers/usb/host/uhci-hcd.c: USB Universal Host
Controller Interface driver v2.1
Jan 29 17:20:47 purple kernel: PCI: Found IRQ 11 for device 0000:00:1d.0
Jan 29 17:20:47 purple kernel: PCI: Sharing IRQ 11 with 0000:01:00.0
Jan 29 17:20:47 purple kernel: PCI: Sharing IRQ 11 with 0000:02:00.0
Jan 29 17:20:47 purple kernel: uhci_hcd 0000:00:1d.0: UHCI Host Controller
Jan 29 17:20:47 purple kernel: PCI: Setting latency timer of device 0000:00:1d.0 to 64
Jan 29 17:20:47 purple kernel: uhci_hcd 0000:00:1d.0: irq 11, io base 00001800
Jan 29 17:20:47 purple kernel: uhci_hcd 0000:00:1d.0: new USB bus registered, assigned
bus number 1
Jan 29 17:20:47 purple kernel: drivers/usb/host/uhci-hcd.c: detected 2 ports
Jan 29 17:20:47 purple kernel: uhci_hcd 0000:00:1d.0: root hub device address 1
Jan 29 17:20:47 purple kernel: usb usb1: new device strings: Mfr=3, Product=2,
SerialNumber=1
Jan 29 17:20:47 purple kernel: drivers/usb/core/message.c: USB device number 1 default
language ID 0x409
Jan 29 17:20:47 purple kernel: usb usb1: Product: UHCI Host Controller
Jan 29 17:20:47 purple kernel: usb usb1: Manufacturer: Linux 2.6.1 uhci_hcd
Jan 29 17:20:47 purple kernel: usb usb1: SerialNumber: 0000:00:1d.0
Jan 29 17:20:47 purple kernel: drivers/usb/core/usb.c: usb_hotplug
Jan 29 17:20:47 purple kernel: usb usb1: registering 1-0:1.0 (config #1, interface 0)
Jan 29 17:20:47 purple kernel: drivers/usb/core/usb.c: usb_hotplug
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: usb_probe_interface
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: usb_probe_interface - got id
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: USB hub found
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: 2 ports detected
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: standalone hub
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: ganged power switching
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: global over-current protection
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: Port indicators are not supported
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: power on to power good time: 2ms
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: hub controller current requirement: 0mA
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: local power source is good
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: no over-current condition exists
Jan 29 17:20:47 purple kernel: hub 1-0:1.0: enabling power on all ports
Jan 29 17:20:47 purple kernel: PCI: Found IRQ 11 for device 0000:00:1d.1
Jan 29 17:20:47 purple kernel: uhci_hcd 0000:00:1d.1: UHCI Host Controller
Jan 29 17:20:47 purple kernel: PCI: Setting latency timer of device 0000:00:1d.1 to 64
Jan 29 17:20:47 purple kernel: uhci_hcd 0000:00:1d.1: irq 11, io base 00001820
Jan 29 17:20:47 purple kernel: uhci_hcd 0000:00:1d.1: new USB bus registered, assigned
bus number 2
Jan 29 17:20:47 purple kernel: drivers/usb/host/uhci-hcd.c: detected 2 ports
Jan 29 17:20:47 purple kernel: uhci_hcd 0000:00:1d.1: root hub device address 1
Jan 29 17:20:47 purple kernel: usb usb2: new device strings: Mfr=3, Product=2,
SerialNumber=1
Jan 29 17:20:47 purple kernel: drivers/usb/core/message.c: USB device number 1 default
language ID 0x409
Jan 29 17:20:47 purple kernel: usb usb2: Product: UHCI Host Controller
Jan 29 17:20:47 purple kernel: usb usb2: Manufacturer: Linux 2.6.1 uhci_hcd
Jan 29 17:20:47 purple kernel: usb usb2: SerialNumber: 0000:00:1d.1
Jan 29 17:20:47 purple kernel: drivers/usb/core/usb.c: usb_hotplug
Jan 29 17:20:47 purple kernel: usb usb2: registering 2-0:1.0 (config #1, interface 0)
Jan 29 17:20:47 purple kernel: drivers/usb/core/usb.c: usb_hotplug
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: usb_probe_interface
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: usb_probe_interface - got id
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: USB hub found
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: 2 ports detected
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: standalone hub
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: ganged power switching
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: global over-current protection
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: Port indicators are not supported
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: power on to power good time: 2ms
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: hub controller current requirement: 0mA
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: local power source is good
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: no over-current condition exists
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: enabling power on all ports
Jan 29 17:20:47 purple kernel: PCI: Found IRQ 11 for device 0000:00:1d.2
Jan 29 17:20:47 purple kernel: PCI: Sharing IRQ 11 with 0000:00:1f.1
Jan 29 17:20:47 purple kernel: PCI: Sharing IRQ 11 with 0000:02:02.0
Jan 29 17:20:47 purple kernel: uhci_hcd 0000:00:1d.2: UHCI Host Controller
Jan 29 17:20:47 purple kernel: PCI: Setting latency timer of device 0000:00:1d.2 to 64
Jan 29 17:20:47 purple kernel: uhci_hcd 0000:00:1d.2: irq 11, io base 00001840
Jan 29 17:20:47 purple kernel: uhci_hcd 0000:00:1d.2: new USB bus registered, assigned
bus number 3
Jan 29 17:20:47 purple kernel: drivers/usb/host/uhci-hcd.c: detected 2 ports
Jan 29 17:20:47 purple kernel: uhci_hcd 0000:00:1d.2: root hub device address 1
Jan 29 17:20:47 purple kernel: usb usb3: new device strings: Mfr=3, Product=2,
SerialNumber=1
Jan 29 17:20:47 purple kernel: drivers/usb/core/message.c: USB device number 1 default
language ID 0x409
Jan 29 17:20:47 purple kernel: usb usb3: Product: UHCI Host Controller
Jan 29 17:20:47 purple kernel: usb usb3: Manufacturer: Linux 2.6.1 uhci_hcd
Jan 29 17:20:47 purple kernel: usb usb3: SerialNumber: 0000:00:1d.2
Jan 29 17:20:47 purple kernel: drivers/usb/core/usb.c: usb_hotplug
Jan 29 17:20:47 purple kernel: usb usb3: registering 3-0:1.0 (config #1, interface 0)
Jan 29 17:20:47 purple kernel: drivers/usb/core/usb.c: usb_hotplug
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: usb_probe_interface
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: usb_probe_interface - got id
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: USB hub found
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: 2 ports detected
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: standalone hub
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: ganged power switching
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: global over-current protection
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: Port indicators are not supported
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: power on to power good time: 2ms
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: hub controller current requirement: 0mA
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: local power source is good
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: no over-current condition exists
Jan 29 17:20:47 purple kernel: hub 3-0:1.0: enabling power on all ports
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: port 1, status 301, change 1, 1.5 Mb/s
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: debounce: port 1: delay 100ms stable 4
status 0x301
Jan 29 17:20:47 purple kernel: hub 2-0:1.0: new USB device on port 1, assigned address
2
Jan 29 17:20:47 purple kernel: usb 2-1: new device strings: Mfr=1, Product=2,
SerialNumber=0
Jan 29 17:20:47 purple kernel: drivers/usb/core/message.c: USB device number 2 default
language ID 0x409
Jan 29 17:20:47 purple kernel: usb 2-1: Product: Microsoft Wireless Optical Desktop�
1.00
Jan 29 17:20:47 purple kernel: usb 2-1: Manufacturer: Microsoft
Jan 29 17:20:47 purple kernel: drivers/usb/core/usb.c: usb_hotplug
Jan 29 17:20:47 purple kernel: usb 2-1: registering 2-1:1.0 (config #1, interface 0)
Jan 29 17:20:47 purple kernel: drivers/usb/core/usb.c: usb_hotplug
Jan 29 17:20:47 purple kernel: usb 2-1: registering 2-1:1.1 (config #1, interface 1)
Jan 29 17:20:47 purple kernel: drivers/usb/core/usb.c: usb_hotplug
Jan 29 17:20:47 purple kernel: drivers/usb/host/uhci-hcd.c: 1800: suspend_hc
Jan 29 17:20:47 purple kernel: drivers/usb/host/uhci-hcd.c: 1840: suspend_hc
Jan 29 17:20:47 purple automount[438]: starting automounter version 4.0.0, path =
/var/autofs/misc, maptype = file, mapname = /etc/auto.misc
Jan 29 17:20:47 purple automount[438]: Map argc = 1
Jan 29 17:20:47 purple automount[438]: Map argv[0] = /etc/auto.misc
Jan 29 17:20:47 purple automount[443]: starting automounter version 4.0.0, path =
/var/autofs/net, maptype = program, mapname = /etc/auto.net
Jan 29 17:20:47 purple automount[443]: Map argc = 1
Jan 29 17:20:47 purple automount[443]: Map argv[0] = /etc/auto.net
Jan 29 17:20:47 purple automount[448]: starting automounter version 4.0.0, path =
/var/autofs/removable, maptype = file, mapname = /etc/auto.removable
Jan 29 17:20:47 purple automount[448]: Map argc = 1
Jan 29 17:20:47 purple automount[448]: Map argv[0] = /etc/auto.removable
Jan 29 17:20:47 purple automount[443]: mount(bind): bind_works = 1
Jan 29 17:20:47 purple automount[438]: mount(bind): bind_works = 1
Jan 29 17:20:47 purple automount[448]: mount(bind): bind_works = 1
Jan 29 17:20:48 purple usb.agent[142]: kernel driver usbcore already loaded
Jan 29 17:20:48 purple usb.agent[166]: kernel driver usbcore already loaded
Jan 29 17:20:48 purple usb.agent[183]: kernel driver usbcore already loaded
Jan 29 17:20:48 purple automount[443]: using kernel protocol version 4
Jan 29 17:20:48 purple automount[443]: using timeout 300 seconds; freq 75 secs
Jan 29 17:20:48 purple automount[438]: using kernel protocol version 4
Jan 29 17:20:48 purple automount[438]: using timeout 300 seconds; freq 75 secs
Jan 29 17:20:48 purple automount[448]: using kernel protocol version 4
Jan 29 17:20:48 purple automount[448]: using timeout 3 seconds; freq 1 secs
Jan 29 17:20:48 purple kernel: drivers/usb/core/usb.c: registered new driver hiddev
Jan 29 17:20:48 purple kernel: hid 2-1:1.0: usb_probe_interface
Jan 29 17:20:48 purple kernel: hid 2-1:1.0: usb_probe_interface - got id
Jan 29 17:20:48 purple usb.agent[213]: kernel driver hid already loaded
Jan 29 17:20:48 purple kernel: Linux Kernel Card Services
Jan 29 17:20:48 purple kernel: options: [pci] [cardbus] [pm]
Jan 29 17:20:48 purple kernel: PCI: Found IRQ 11 for device 0000:02:00.0
Jan 29 17:20:48 purple kernel: PCI: Sharing IRQ 11 with 0000:00:1d.0
Jan 29 17:20:48 purple kernel: PCI: Sharing IRQ 11 with 0000:01:00.0
Jan 29 17:20:48 purple kernel: Yenta: CardBus bridge found at 0000:02:00.0 [1014:023b]
Jan 29 17:20:48 purple kernel: Yenta: Using INTVAL to route CSC interrupts to PCI
Jan 29 17:20:48 purple kernel: Yenta: Routing CardBus interrupts to PCI
Jan 29 17:20:48 purple kernel: input: USB HID v1.11 Keyboard [Microsoft Microsoft
Wireless Optical Desktop� 1.00] on usb-0000:00:1d.1-1
Jan 29 17:20:48 purple kernel: hid 2-1:1.1: usb_probe_interface
Jan 29 17:20:48 purple kernel: hid 2-1:1.1: usb_probe_interface - got id
Jan 29 17:20:48 purple kernel: Yenta: ISA IRQ mask 0x06b8, PCI irq 11
Jan 29 17:20:48 purple kernel: Socket status: 30000006
Jan 29 17:20:48 purple kernel: PCI: Found IRQ 11 for device 0000:02:00.1
Jan 29 17:20:48 purple kernel: PCI: Sharing IRQ 11 with 0000:00:1f.3
Jan 29 17:20:48 purple kernel: PCI: Sharing IRQ 11 with 0000:00:1f.5
Jan 29 17:20:48 purple kernel: Yenta: CardBus bridge found at 0000:02:00.1 [1014:023b]
Jan 29 17:20:48 purple kernel: Yenta: Using INTVAL to route CSC interrupts to PCI
Jan 29 17:20:48 purple kernel: Yenta: Routing CardBus interrupts to PCI
Jan 29 17:20:48 purple modprobe: FATAL: Could not open
'/lib/modules/2.6.1/kernel/drivers/input/evbug.ko': No such file or directory
Jan 29 17:20:48 purple modprobe: FATAL: Could not open
'/lib/modules/2.6.1/kernel/drivers/input/evbug.ko': No such file or directory
Jan 29 17:20:48 purple kernel: input: USB HID v1.11 Mouse [Microsoft Microsoft
Wireless Optical Desktop� 1.00] on usb-0000:00:1d.1-1
Jan 29 17:20:48 purple kernel: drivers/usb/core/usb.c: registered new driver hid
Jan 29 17:20:48 purple kernel: drivers/usb/input/hid-core.c: v2.0:USB HID core driver
Jan 29 17:20:49 purple kernel: Yenta: ISA IRQ mask 0x06b8, PCI irq 11
Jan 29 17:20:49 purple kernel: Socket status: 30000006
Jan 29 17:20:49 purple cardmgr[636]: watching 2 sockets
Jan 29 17:20:49 purple kernel: cs: IO port probe 0x0c00-0x0cff: clean.
Jan 29 17:20:49 purple kernel: cs: IO port probe 0x0800-0x08ff: clean.
Jan 29 17:20:49 purple kernel: cs: IO port probe 0x0100-0x04ff: excluding 0x3c0-0x3df
0x3f8-0x3ff 0x4d0-0x4d7
Jan 29 17:20:49 purple kernel: cs: IO port probe 0x0a00-0x0aff: clean.
Jan 29 17:15:23 purple apmd[334]: Suspending now
Jan 29 17:15:23 purple kernel: hdc: start_power_step(step: 0)
Jan 29 17:15:23 purple kernel: hdc: completing PM request, suspend
Jan 29 17:15:23 purple kernel: hda: start_power_step(step: 0)
Jan 29 17:15:23 purple kernel: hda: start_power_step(step: 1)
Jan 29 17:15:24 purple kernel: hda: complete_power_step(step: 1, stat: 50, err: 0)
Jan 29 17:15:24 purple kernel: hda: completing PM request, suspend
Jan 29 17:15:31 purple kernel: uhci_hcd 0000:00:1d.2: suspend D4 --> D3
Jan 29 17:15:31 purple kernel: drivers/usb/host/uhci-hcd.c: 1840: suspend_hc
Jan 29 17:15:31 purple kernel: uhci_hcd 0000:00:1d.1: suspend D4 --> D3
Jan 29 17:15:31 purple kernel: drivers/usb/host/uhci-hcd.c: 1820: suspend_hc
Jan 29 17:15:31 purple kernel: uhci_hcd 0000:00:1d.0: suspend D4 --> D3
Jan 29 17:15:31 purple kernel: drivers/usb/host/uhci-hcd.c: 1800: suspend_hc
Jan 29 17:15:31 purple kernel: uhci_hcd 0000:00:1d.0: resume from state D4
Jan 29 17:15:31 purple kernel: PCI: Setting latency timer of device 0000:00:1d.0 to 64
Jan 29 17:15:31 purple kernel: uhci_hcd 0000:00:1d.1: resume from state D4
Jan 29 17:15:31 purple kernel: PCI: Setting latency timer of device 0000:00:1d.1 to 64
Jan 29 17:15:31 purple kernel: uhci_hcd 0000:00:1d.2: resume from state D4
Jan 29 17:15:31 purple kernel: PCI: Setting latency timer of device 0000:00:1d.2 to 64
Jan 29 17:15:31 purple kernel: hda: Wakeup request inited, waiting for !BSY...
Jan 29 17:15:31 purple kernel: hda: start_power_step(step: 1000)
Jan 29 17:15:31 purple kernel: hda: completing PM request, resume
Jan 29 17:15:31 purple kernel: hdc: Wakeup request inited, waiting for !BSY...
Jan 29 17:15:31 purple kernel: hdc: start_power_step(step: 1000)
Jan 29 17:15:31 purple kernel: hdc: completing PM request, resume
Jan 29 17:15:31 purple kernel: drivers/usb/host/uhci-hcd.c: 1800: wakeup_hc
Jan 29 17:15:31 purple kernel: drivers/usb/host/uhci-hcd.c: 1840: wakeup_hc
Jan 29 17:15:31 purple kernel: drivers/usb/host/uhci-hcd.c: 1820: wakeup_hc
Jan 29 17:15:32 purple apmd[334]: Proxy exited with status 1
Jan 29 17:18:06 purple kernel: rmmod D DBD4D973 0 2024 844
(NOTLB)
Jan 29 17:18:06 purple kernel: d3ee1db4 00000086 d7f012a0 dbd4d973 00000000 c013ecdf
d3ee1d98 d3e0b2ac
Jan 29 17:18:06 purple kernel: 00000000 dbd4d973 00000000 d7f012a0 d7f012c0
00003d47 dbe5530c 00000000
Jan 29 17:18:06 purple kernel: d7207500 d3e67658 00000292 d3ee0000 d7207340
c0107df5 d3e67660 00000001
Jan 29 17:18:06 purple kernel: Call Trace:
Jan 29 17:18:06 purple kernel: [truncate_inode_pages+287/656]
truncate_inode_pages+0x11f/0x290
Jan 29 17:18:06 purple kernel: [__down+149/272] __down+0x95/0x110
Jan 29 17:18:06 purple kernel: [default_wake_function+0/32]
default_wake_function+0x0/0x20
Jan 29 17:18:06 purple kernel: [invalidate_inode_buffers+17/128]
invalidate_inode_buffers+0x11/0x80
Jan 29 17:18:06 purple kernel: [__down_failed+8/12] __down_failed+0x8/0xc
Jan 29 17:18:06 purple kernel: [__crc_buffer_insert_list+2453411/3736768]
.text.lock.hub+0x5/0x9d [usbcore]
Jan 29 17:18:06 purple kernel: [__crc_buffer_insert_list+2465291/3736768]
usb_disable_endpoint+0x70/0x80 [usbcore]
Jan 29 17:18:06 purple kernel: [__crc_buffer_insert_list+2435746/3736768]
usb_unbind_interface+0x77/0x80 [usbcore]
Jan 29 17:18:06 purple kernel: [device_release_driver+102/112]
device_release_driver+0x66/0x70
Jan 29 17:18:06 purple kernel: [bus_remove_device+85/160] bus_remove_device+0x55/0xa0
Jan 29 17:18:06 purple kernel: [device_del+93/160] device_del+0x5d/0xa0
Jan 29 17:18:06 purple kernel: [__crc_buffer_insert_list+2465619/3736768]
usb_disable_device+0xd8/0x120 [usbcore]
Jan 29 17:18:06 purple kernel: [__crc_buffer_insert_list+2438671/3736768]
usb_disconnect+0xa4/0x140 [usbcore]
Jan 29 17:18:06 purple kernel: [__crc_buffer_insert_list+2476412/3736768]
usb_hcd_pci_remove+0xb1/0x1b0 [usbcore]
Jan 29 17:18:06 purple kernel: [pci_device_remove+59/64] pci_device_remove+0x3b/0x40
Jan 29 17:18:06 purple kernel: [device_release_driver+102/112]
device_release_driver+0x66/0x70
Jan 29 17:18:06 purple kernel: [driver_detach+43/64] driver_detach+0x2b/0x40
Jan 29 17:18:06 purple kernel: [bus_remove_driver+61/128] bus_remove_driver+0x3d/0x80
Jan 29 17:18:06 purple kernel: [driver_unregister+19/40] driver_unregister+0x13/0x28
Jan 29 17:18:06 purple kernel: [pci_unregister_driver+22/48]
pci_unregister_driver+0x16/0x30
Jan 29 17:18:06 purple kernel: [__crc_buffer_insert_list+2386330/3736768]
uhci_hcd_cleanup+0xf/0x5e [uhci_hcd]
Jan 29 17:18:06 purple kernel: [sys_delete_module+313/432]
sys_delete_module+0x139/0x1b0
Jan 29 17:18:06 purple kernel: [do_munmap+144/400] do_munmap+0x90/0x190
Jan 29 17:18:06 purple kernel: [syscall_call+7/11] syscall_call+0x7/0xb
