Hi, David!
Thank you for reply!
On Tue, 23 Mar 2004, David Brownell wrote:
> > The relevant kernel messages with 2.6.5-rc1 are:
>
> Actually these aren't enough; you mentioned "the devices", present,
> this doesn't even show all the enumeration of one of them.
>
> Try enabling CONFIG_USB_DEBUG and then forward the entire dmesg
> output for USB enumeration (initial), removing a device (flag
> where that was in the log), and re-inserting a new one (also flag
> that point).
>
> If it doesn't show anything for the removal, then make sure
> to include alt-sysrq-t output for (just) khubd.
Done. The annotated kernel log is attached. The khubd trace is at the
end.
Later I rebooted with two keyboards and both worked. After I disconnected
the native Apple keyboard, it stopped working but still was shown by
lsusb. This probably indicates that the problem is not with that
keyboard, but with disconnects. Everything is fine until a device is
disconnected. Once it's disconnected, that port is dead.
--
Regards,
Pavel Roskin
Total memory = 768MB; using 2048kB for hash table (at c0400000)
Linux version 2.6.5-rc2 ([EMAIL PROTECTED]) (gcc version 3.3.3 (Debian 20040320)) #2
Wed Mar 24 00:21:35 EST 2004
Found a Paddington mac-io controller, rev: 0, mapped at 0xfdf80000
PowerMac motherboard: Blue&White G3
Found Grackle (MPC106) PCI host bridge at 0x80000000. Firmware bus number: 0->1
nvram: OF partition at 0x140
nvram: XP partition at 0x11b0
nvram: NR partition at 0x12b0
On node 0 totalpages: 196608
DMA zone: 196608 pages, LIFO batch:16
Normal zone: 0 pages, LIFO batch:1
HighMem zone: 0 pages, LIFO batch:1
Built 1 zonelists
Kernel command line: root=/dev/hda7 ro console=ttyS0,115200
System has 64 possible interrupts
PID hash table entries: 4096 (order 12: 32768 bytes)
GMT Delta read from XPRAM: -300 minutes, DST: off
via_calibrate_decr: ticks per jiffy = 24934 (1496094 ticks)
Console: colour dummy device 80x25
Memory: 773120k available (1636k kernel code, 996k data, 120k init, 0k highmem)
Calibrating delay loop... 595.96 BogoMIPS
Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Mount-cache hash table entries: 512 (order: 0, 4096 bytes)
POSIX conformance testing by UNIFIX
NET: Registered protocol family 16
PCI: Probing PCI hardware
Registering pmac pic with sysfs...
drivers/usb/core/usb.c: registered new driver usbfs
drivers/usb/core/usb.c: registered new driver hub
PCI: Enabling device 0000:00:10.0 (0086 -> 0087)
aty128fb: Found Open Firmware ROM Image
aty128fb: BIOS not located, guessing timings.
aty128fb: Rage128 RE PCI [chip rev 0x2] 16M 128-bit SDR SGRAM (1:1)
fb0: ATY Rage128 frame buffer device on Rage128 RE PCI
devfs: 2004-01-31 Richard Gooch ([EMAIL PROTECTED])
devfs: boot_options: 0x1
Console: switching to colour frame buffer device 128x48
Generic RTC Driver v1.07
Macintosh non-volatile memory driver v1.1
[drm] Initialized r128 2.5.0 20030725 on minor 0
pmac_zilog: 0.6 (Benjamin Herrenschmidt <[EMAIL PROTECTED]>)
ttyS0 at MMIO 0x80813020 (irq = 15) is a Z85c30 ESCC - Serial port
ttyS1 at MMIO 0x80813000 (irq = 16) is a Z85c30 ESCC - Infrared port
loop: loaded (max 8 devices)
MacIO PCI driver attached to Paddington chipset
Can't request resource 0 for MacIO device 0.00000000:power-mg
00:50:e4:40:9e:43<6>eth0: BMAC+ at
Macintosh CUDA driver v0.5 for Unified ADB.
Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
CMD646: IDE controller at PCI slot 0000:01:01.0
CMD646: chipset revision 5
CMD646: chipset revision 0x05, UltraDMA Capable
CMD646: 100% native mode on irq 26
ide0: BM-DMA at 0x1060-0x1067, BIOS settings: hda:pio, hdb:pio
ide1: BM-DMA at 0x1068-0x106f, BIOS settings: hdc:pio, hdd:pio
Probing IDE interface ide0...
hda: QUANTUM FIREBALL EX6.4A, ATA DISK drive
Unhandled interrupt 1a, disabled
Using anticipatory io scheduler
ide0 at 0x10a0-0x10a7,0x1092 on irq 26
Probing IDE interface ide1...
ide1: Wait for ready failed before probe !
ide2: Found Apple Heathrow ATA controller, bus ID 0, irq 13
Probing IDE interface ide2...
hde: CD-ROM CDU701-25, ATAPI CD/DVD-ROM drive
hdf: IOMEGA ZIP 100 ATAPI, ATAPI FLOPPY drive
Unhandled interrupt d, disabled
hde: MDMA, cycleTime: 120, accessTime: 75, recTime: 45
hde: Set MDMA timing for mode 2, reg: 0x00211526
hde: Enabling MultiWord DMA 2
ide2 at 0xf200c000-0xf200c007,0xf200c160 on irq 13
hda: max request size: 128KiB
hda: 12594960 sectors (6448 MB) w/418KiB Cache, CHS=13328/15/63, UDMA(33)
/dev/ide/host0/bus0/target0/lun0: [mac] p1 p2 p3 p4 p5 p6 p7 p8 p9 p10
hde: ATAPI 32X CD-ROM drive, 128kB Cache, DMA
Uniform CD-ROM driver Revision: 3.20
ohci_hcd: 2004 Feb 02 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI)
ohci_hcd: block sizes: ed 64 td 64
ohci_hcd 0000:01:06.0: OPTi Inc. 82C861
ohci_hcd 0000:01:06.0: reset, control = 0x0
ohci_hcd 0000:01:06.0: irq 28, pci mem f2015000
ohci_hcd 0000:01:06.0: new USB bus registered, assigned bus number 1
ohci_hcd 0000:01:06.0: WARNING: OPTi workarounds unavailable
ohci_hcd 0000:01:06.0: root hub device address 1
usb usb1: new device strings: Mfr=3, Product=2, SerialNumber=1
drivers/usb/core/message.c: USB device number 1 default language ID 0x409
usb usb1: Product: OPTi Inc. 82C861
usb usb1: Manufacturer: Linux 2.6.5-rc2 ohci_hcd
usb usb1: SerialNumber: 0000:01:06.0
drivers/usb/core/usb.c: usb_hotplug
usb usb1: registering 1-0:1.0 (config #1, interface 0)
drivers/usb/core/usb.c: usb_hotplug
hub 1-0:1.0: usb_probe_interface
hub 1-0:1.0: usb_probe_interface - got id
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 2 ports detected
hub 1-0:1.0: standalone hub
hub 1-0:1.0: unknown reserved power switching mode
hub 1-0:1.0: global over-current protection
hub 1-0:1.0: Port indicators are not supported
hub 1-0:1.0: power on to power good time: 6ms
hub 1-0:1.0: hub controller current requirement: 0mA
hub 1-0:1.0: local power source is good
hub 1-0:1.0: no over-current condition exists
hub 1-0:1.0: enabling power on all ports
ohci_hcd 0000:01:06.0: created debug files
ohci_hcd 0000:01:06.0: OHCI controller state
ohci_hcd 0000:01:06.0: OHCI 1.0, with legacy support registers
ohci_hcd 0000:01:06.0: control 0x083 HCFS=operational CBSR=3
ohci_hcd 0000:01:06.0: cmdstatus 0x00000 SOC=0
ohci_hcd 0000:01:06.0: intrstatus 0x00000044 RHSC SF
ohci_hcd 0000:01:06.0: intrenable 0x80000002 MIE WDH
ohci_hcd 0000:01:06.0: hcca frame #0028
ohci_hcd 0000:01:06.0: roothub.a 03000202 POTPGT=3 NPS NDP=2
ohci_hcd 0000:01:06.0: roothub.b 00000000 PPCM=0000 DR=0000
ohci_hcd 0000:01:06.0: roothub.status 00000000
ohci_hcd 0000:01:06.0: roothub.portstatus [0] 0x00000100 PPS
ohci_hcd 0000:01:06.0: roothub.portstatus [1] 0x00010301 CSC LSDA PPS CCS
drivers/usb/core/usb.c: registered new driver hid
drivers/usb/input/hid-core.c: v2.0:USB HID core driver
mice: PS/2 mouse device common for all mice
NET: Registered protocol family 2
IP: routing cache hash table of 8192 buckets, 64Kbytes
TCP: Hash tables configured (established 262144 bind 65536)
NET: Registered protocol family 1
NET: Registered protocol family 17
kjournald starting. Commit interval 5 seconds
EXT3-fs: mounted filesystem with ordered data mode.
VFS: Mounted root (ext3 filesystem) readonly.
Mounted devfs on /dev
Freeing unused kernel memory: 120k init 4k chrp 8k prep
ohci_hcd 0000:01:06.0: GetStatus roothub.portstatus [2] = 0x00010301 CSC LSDA PPS CCS
hub 1-0:1.0: port 2, status 301, change 1, 1.5 Mb/s
hub 1-0:1.0: debounce: port 2: delay 100ms stable 4 status 0x301
ohci_hcd 0000:01:06.0: GetStatus roothub.portstatus [2] = 0x00100303 PRSC LSDA PPS PES
CCS
usb 1-2: new low speed USB device using address 2
usb 1-2: new device strings: Mfr=1, Product=2, SerialNumber=0
drivers/usb/core/message.c: USB device number 2 default language ID 0x409
usb 1-2: Product: USB Keyboard and Mouse
usb 1-2: Manufacturer: FOCUS
drivers/usb/core/usb.c: usb_hotplug
usb 1-2: registering 1-2:1.0 (config #1, interface 0)
drivers/usb/core/usb.c: usb_hotplug
hid 1-2:1.0: usb_probe_interface
hid 1-2:1.0: usb_probe_interface - got id
input: USB HID v1.00 Keyboard [FOCUS USB Keyboard and Mouse] on usb-0000:01:06.0-2
usb 1-2: registering 1-2:1.1 (config #1, interface 1)
drivers/usb/core/usb.c: usb_hotplug
hid 1-2:1.1: usb_probe_interface
hid 1-2:1.1: usb_probe_interface - got id
input: USB HID v1.00 Mouse [FOCUS USB Keyboard and Mouse] on usb-0000:01:06.0-2
Adding 311496k swap on /dev/hda8. Priority:-1 extents:1
EXT3 FS on hda7, internal journal
Linux Kernel Card Services
options: [pci] [cardbus]
PCI: Enabling device 0000:01:03.0 (0000 -> 0002)
Yenta: CardBus bridge found at 0000:01:03.0 [0000:0000]
Yenta: Enabling burst memory read transactions
Yenta: Using CSCINT to route CSC interrupts to PCI
Yenta: Routing CardBus interrupts to PCI
Yenta: ISA IRQ mask 0x0000, PCI irq 24
Socket status: 30000006
phy registers:
1400 782d 7810 0001 00a1 45e1 0001 0000
0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 4000 0000 2ffb 0010 0000 0002
0001 0000 0000 0000 0000 0000 0000 0000
Boot ends here. Focus keyboard with integrated mouse is connected to
one port. The other port is empty.
Connecting Netgear MA101 rev B to the free port.
usbfs: USBDEVFS_CONTROL failed cmd lsusb dev 2 rqt 128 rq 6 len 65 ret -32
usbfs: USBDEVFS_CONTROL failed cmd lsusb dev 2 rqt 128 rq 6 len 79 ret -32
usbfs: USBDEVFS_CONTROL failed cmd lsusb dev 2 rqt 128 rq 6 len 65 ret -32
usbfs: USBDEVFS_CONTROL failed cmd lsusb dev 2 rqt 128 rq 6 len 79 ret -32
ohci_hcd 0000:01:06.0: GetStatus roothub.portstatus [1] = 0x00010101 CSC PPS CCS
hub 1-0:1.0: port 1, status 101, change 1, 12 Mb/s
hub 1-0:1.0: debounce: port 1: delay 100ms stable 4 status 0x101
ohci_hcd 0000:01:06.0: GetStatus roothub.portstatus [1] = 0x00100103 PRSC PPS PES CCS
usb 1-1: new full speed USB device using address 3
usb 1-1: new device strings: Mfr=0, Product=0, SerialNumber=0
drivers/usb/core/usb.c: usb_hotplug
usb 1-1: registering 1-1:1.0 (config #1, interface 0)
drivers/usb/core/usb.c: usb_hotplug
Netgear MA101 rev B connected. Now disconnecting it.
usbfs: USBDEVFS_CONTROL failed cmd lsusb dev 2 rqt 128 rq 6 len 65 ret -32
usbfs: USBDEVFS_CONTROL failed cmd lsusb dev 2 rqt 128 rq 6 len 79 ret -32
ohci_hcd 0000:01:06.0: GetStatus roothub.portstatus [1] = 0x00030100 PESC CSC PPS
hub 1-0:1.0: port 1, status 100, change 3, 12 Mb/s
usb 1-1: USB disconnect, address 3
usb 1-1: usb_disable_device nuking all URBs
usb 1-1: unregistering interface 1-1:1.0
drivers/usb/core/usb.c: usb_hotplug
Netgear MA101 rev B disconnected. Connecting the native Apple keyboard
to the free port. The Focus keyboard is still connected.
usbfs: USBDEVFS_CONTROL failed cmd lsusb dev 2 rqt 128 rq 6 len 65 ret -32
usbfs: USBDEVFS_CONTROL failed cmd lsusb dev 2 rqt 128 rq 6 len 79 ret -32
The Apple keyboard is connected but doesn't seem to work. The Focus
keyboard is still working. Disconnecting the Apple keyboard.
usbfs: USBDEVFS_CONTROL failed cmd lsusb dev 2 rqt 128 rq 6 len 65 ret -32
usbfs: USBDEVFS_CONTROL failed cmd lsusb dev 2 rqt 128 rq 6 len 79 ret -32
The Apple keyboard is diconnected. The focus keyboard is connected.
lsusb shows the Netgear adaptor that is long gone:
# lsusb
Bus 001 Device 003: ID 0864:4102 NetGear, Inc. MA101 802.11b Adapter
Bus 001 Device 002: ID 05b2:7200
Bus 001 Device 001: ID 0000:0000
Now pressing Alt-SysRq. The khubd trace is:
khubd D 00000000 0 5 1 8 3 (L-TLB)
Call trace:
[c0008a64] __switch_to+0x48/0x70
[c00155d4] schedule+0x398/0x764
[c0015c6c] wait_for_completion+0x7c/0xec
[c012d7f4] usb_disable_device+0xe8/0x154
[c0127408] usb_disconnect+0xa0/0x174
[c0129aa0] hub_port_connect_change+0x2e4/0x2e8
[c0129e1c] hub_events+0x378/0x430
[c0129f10] hub_thread+0x3c/0xec
[c0008950] kernel_thread+0x44/0x60
