Ok, I've added everyone who has reported this kind of problem with the usb stack to this bug. If you don't want to be on it, please feel free to remove yourself. The problem is that the error: device not accepting address XX, error -71 is happening for some device in your system, on the latest kernel version. But unfortunatly, it's not easily reproducable for anyone in the Linux USB developer group. None of my devices or systems cause this error. So, any help in trying to debug this would be greatly appreciated. I know Alan has a suggested patch that might help people out, if he could attach it and people could try it out, that would be very helpful. Also, if people use git, could you try to use 'git bisect' to determine exactly what change caused this to happen? I think it was working fine for 2.6.13 or maybe 2.6.14, but 2.6.15 and later has the problem reported for it.
This is also reported in Novell's bugzilla at: https://bugzilla.novell.com/show_bug.cgi?id=145079
Created attachment 7360 [details] Increase the reset recovery delay time Here's the patch Greg mentioned. It helped one user with an iPod; I'm sure it won't help everybody. In that case the problem was in the iPod, but the general problem appears to lie in the host controller hardware.
A related bug report is #5777. Len Brown found that the internal hub in his laptop's docking station wouldn't accept an address until he reloaded ehci-hcd. However, he also found that using a different .config fixed it. Nobody has tried to duplicate his results or interpolate between the two .configs to see if the problem can be traced to a single item.
I have this problem on a bus-powered High-speed USB drive. If I plug it into the onboard OHCI controller, it works. If I plug it into an add-in ALI OHCI/EHCI card, I get the -71 error. I haven't tried unloading the EHCI component. This machine is a Serverworks Dual P3 box and is running the Fedora 2.6.15-1.1833_FC4smp kernel; the USB disk is for backup purposes. Due to the production nature of the machine I can't take it down for random tests. 'lspci -v' output: 00:02.0 USB Controller: ALi Corporation USB 1.1 Controller (rev 03) (prog-if 10 [OHCI]) Subsystem: ALi Corporation USB 1.1 Controller Flags: bus master, 66Mhz, medium devsel, latency 64, IRQ 201 Memory at fdaee000 (32-bit, non-prefetchable) [size=4K] Capabilities: <available only to root> 00:02.3 USB Controller: ALi Corporation USB 2.0 Controller (rev 01) (prog-if 20 [EHCI]) Subsystem: Unknown device 2020:8888 Flags: bus master, 66Mhz, medium devsel, latency 64, IRQ 209 Memory at fdaefe00 (32-bit, non-prefetchable) [size=256] Capabilities: <available only to root> 'lsusb' output: Bus 002 Device 004: ID 04b4:6830 Cypress Semiconductor Corp. USB-2.0 IDE Adapter
As a FYI, Using Fedora's 2.6.16-1.2069_FC4 kernel (based on 2.6.16.1) I'm still seeing this. If I remove ehci_hcd, the device comes up just fine, albeit at full (ohci) speed. If I unload/reload ehci_hcd, the device still malfunctions: ACPI: PCI Interrupt 0000:00:02.3[A] -> GSI 18 (level, low) -> IRQ 21 ehci_hcd 0000:00:02.3: EHCI Host Controller ehci_hcd 0000:00:02.3: debug port 1 ehci_hcd 0000:00:02.3: new USB bus registered, assigned bus number 1 ehci_hcd 0000:00:02.3: irq 21, io mem 0xfdaefe00 ehci_hcd 0000:00:02.3: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004 usb usb1: configuration #1 chosen from 1 choice hub 1-0:1.0: USB hub found hub 1-0:1.0: 6 ports detected usb 1-1: new high speed USB device using ehci_hcd and address 2 usb 1-1: device not accepting address 2, error -71 usb 1-1: new high speed USB device using ehci_hcd and address 3 usb 1-1: unable to read config index 0 descriptor/start usb 1-1: can't read configurations, error -71 usb 1-1: new high speed USB device using ehci_hcd and address 4 usb 1-1: device not accepting address 4, error -71 usb 1-1: new high speed USB device using ehci_hcd and address 5 usb 1-1: string descriptor 0 read error: -71 usb 1-1: string descriptor 0 read error: -71 usb 1-1: string descriptor 0 read error: -71 usb 1-1: configuration #1 chosen from 1 choice usb 1-1: can't set config #1, error -71 At this point it shows up in 'lsusb' but is otherwise dead. There are a few other load/plug/etc permutations I can try.
Solomon, your problem may simply be caused by a poor USB connection. That is, the cable may work okay at full speed but not at high speed. Check the USB cable and connections. It could also be due to a hardware problem in the disk drive's USB interface. Sometimes there are just some small incompatibilities between pieces of hardware. I've got a USB disk drive and cable that don't work on my home PC. Switching any one of the PC, the cable, or the drive is enough to get things working again.
Created attachment 8021 [details] experimental 2.6.17-rc3 SET_ADDRESS patch Worth maybe trying this patch. When SET_ADDRESS fails, the state of the device is actually indeterminate. Linux currently assumes the operation actually succeeded, which is rather optimistic. This patch makes a more pessimistic assumption.
PING. Have you actually tried the patch? It's been sitting there for over a month now waiting for you ...
I have seen this probolem with my Motorola Phone since 2.6.13 I believe. I just compiled 2.6.17-rc3 with debug turned on for USB, and here is th eoutput if it helps. udev loaded cdc_acm as expected, and the phone charged for ~4 seconds and then quit. Better than 2.6.16 was doing. This was the only time it has worked at all - even for 4 seconds since it has happened after the fresh reboot. removing the modules and reloading them have no affect either. greg@greg-laptop ~ $ lspci 00:00.0 Host bridge: Intel Corporation 82845 845 (Brookdale) Chipset Host Bridge (rev 04) 00:01.0 PCI bridge: Intel Corporation 82845 845 (Brookdale) Chipset AGP Bridge (rev 04) 00:1d.0 USB Controller: Intel Corporation 82801CA/CAM USB (Hub #1) (rev 02) 00:1d.2 USB Controller: Intel Corporation 82801CA/CAM USB (Hub #3) (rev 02) 00:1e.0 PCI bridge: Intel Corporation 82801 Mobile PCI Bridge (rev 42) 00:1f.0 ISA bridge: Intel Corporation 82801CAM ISA Bridge (LPC) (rev 02) 00:1f.1 IDE interface: Intel Corporation 82801CAM IDE U100 (rev 02) 00:1f.5 Multimedia audio controller: Intel Corporation 82801CA/CAM AC'97 Audio Controller (rev 02) 00:1f.6 Modem: Intel Corporation 82801CA/CAM AC'97 Modem Controller (rev 02) 01:00.0 VGA compatible controller: nVidia Corporation NV11 [GeForce2 Go] (rev b2) 02:00.0 Ethernet controller: 3Com Corporation 3c905C-TX/TX-M [Tornado] (rev 78) 02:01.0 CardBus bridge: Texas Instruments PCI4451 PC card Cardbus Controller 02:01.1 CardBus bridge: Texas Instruments PCI4451 PC card Cardbus Controller 02:01.2 FireWire (IEEE 1394): Texas Instruments PCI4451 IEEE-1394 Controller greg@greg-laptop ~ $ lsusb Bus 002 Device 001: ID 0000:0000 Bus 001 Device 002: ID 1241:1177 Belkin F8E842-DL Mouse Bus 001 Device 001: ID 0000:0000 --syslog-- Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: wakeup_rh (auto-start) Jul 8 14:50:29 greg-laptop hub 2-0:1.0: state 7 ports 2 chg 0000 evt 0002 Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: port 1 portsc 0082,00 Jul 8 14:50:29 greg-laptop hub 2-0:1.0: port 1, status 0100, change 0001, 12 Mb/s Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: port 1 portsc 0183,00 Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: port 1 portsc 01a3,00 Jul 8 14:50:29 greg-laptop hub 2-0:1.0: debounce: port 1: total 125ms stable 100ms status 0x301 Jul 8 14:50:29 greg-laptop usb 2-1: new low speed USB device using uhci_hcd and address 2 Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: uhci_result_control: failed with status 440000 Jul 8 14:50:29 greg-laptop [dfc2c380] link (1fc2c2c2) element (1fc2b030) Jul 8 14:50:29 greg-laptop urb_priv [f795d8a8] urb [f7ec93c0] qh [dfc2c380] Dev=0 EP=0(IN) CTL Jul 8 14:50:29 greg-laptop 1: [dfc2b030] link (1fc2b060) e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=37817520) Jul 8 14:50:29 greg-laptop 2: [dfc2b060] link (1fc2b090) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac0) Jul 8 14:50:29 greg-laptop 3: [dfc2b090] link (1fc2b0c0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac8) Jul 8 14:50:29 greg-laptop 4: [dfc2b0c0] link (1fc2b0f0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead0) Jul 8 14:50:29 greg-laptop 5: [dfc2b0f0] link (1fc2b120) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead8) Jul 8 14:50:29 greg-laptop 6: [dfc2b120] link (1fc2b150) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae0) Jul 8 14:50:29 greg-laptop 7: [dfc2b150] link (1fc2b180) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae8) Jul 8 14:50:29 greg-laptop 8: [dfc2b180] link (1fc2b1b0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf0) Jul 8 14:50:29 greg-laptop 9: [dfc2b1b0] link (1fc2b1e0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf8) Jul 8 14:50:29 greg-laptop 10: [dfc2b1e0] link (1fc2b210) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Dummy TD Jul 8 14:50:29 greg-laptop [dfc2b210] link (00000000) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: uhci_result_control: failed with status 440000 Jul 8 14:50:29 greg-laptop [dfc2c380] link (1fc2c2c2) element (1fc2b210) Jul 8 14:50:29 greg-laptop urb_priv [f795d8a8] urb [f7ec93c0] qh [dfc2c380] Dev=0 EP=0(IN) CTL Jul 8 14:50:29 greg-laptop 1: [dfc2b210] link (1fc2b240) e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=37817520) Jul 8 14:50:29 greg-laptop 2: [dfc2b240] link (1fc2b270) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac0) Jul 8 14:50:29 greg-laptop 3: [dfc2b270] link (1fc2b2a0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac8) Jul 8 14:50:29 greg-laptop 4: [dfc2b2a0] link (1fc2b2d0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead0) Jul 8 14:50:29 greg-laptop 5: [dfc2b2d0] link (1fc2b300) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead8) Jul 8 14:50:29 greg-laptop 6: [dfc2b300] link (1fc2b330) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae0) Jul 8 14:50:29 greg-laptop 7: [dfc2b330] link (1fc2b360) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae8) Jul 8 14:50:29 greg-laptop 8: [dfc2b360] link (1fc2b390) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf0) Jul 8 14:50:29 greg-laptop 9: [dfc2b390] link (1fc2b3c0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf8) Jul 8 14:50:29 greg-laptop 10: [dfc2b3c0] link (1fc2b3f0) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Dummy TD Jul 8 14:50:29 greg-laptop [dfc2b3f0] link (00000000) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: uhci_result_control: failed with status 440000 Jul 8 14:50:29 greg-laptop [dfc2c380] link (1fc2c2c2) element (1fc2b3f0) Jul 8 14:50:29 greg-laptop urb_priv [f795d8a8] urb [f7ec93c0] qh [dfc2c380] Dev=0 EP=0(IN) CTL Jul 8 14:50:29 greg-laptop 1: [dfc2b3f0] link (1fc2b030) e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=37817520) Jul 8 14:50:29 greg-laptop 2: [dfc2b030] link (1fc2b060) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac0) Jul 8 14:50:29 greg-laptop 3: [dfc2b060] link (1fc2b090) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac8) Jul 8 14:50:29 greg-laptop 4: [dfc2b090] link (1fc2b0c0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead0) Jul 8 14:50:29 greg-laptop 5: [dfc2b0c0] link (1fc2b0f0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead8) Jul 8 14:50:29 greg-laptop 6: [dfc2b0f0] link (1fc2b120) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae0) Jul 8 14:50:29 greg-laptop 7: [dfc2b120] link (1fc2b150) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae8) Jul 8 14:50:29 greg-laptop 8: [dfc2b150] link (1fc2b180) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf0) Jul 8 14:50:29 greg-laptop 9: [dfc2b180] link (1fc2b1b0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf8) Jul 8 14:50:29 greg-laptop 10: [dfc2b1b0] link (1fc2b1e0) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Dummy TD Jul 8 14:50:29 greg-laptop [dfc2b1e0] link (1fc2b210) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Jul 8 14:50:29 greg-laptop usb 2-1: device descriptor read/64, error -71 Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: uhci_result_control: failed with status 440000 Jul 8 14:50:29 greg-laptop [dfc2c380] link (1fc2c2c2) element (1fc2b1e0) Jul 8 14:50:29 greg-laptop urb_priv [f795d8a8] urb [f7ec93c0] qh [dfc2c380] Dev=0 EP=0(IN) CTL Jul 8 14:50:29 greg-laptop 1: [dfc2b1e0] link (1fc2b030) e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=3551cac0) Jul 8 14:50:29 greg-laptop 2: [dfc2b030] link (1fc2b060) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac0) Jul 8 14:50:29 greg-laptop 3: [dfc2b060] link (1fc2b090) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac8) Jul 8 14:50:29 greg-laptop 4: [dfc2b090] link (1fc2b0c0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead0) Jul 8 14:50:29 greg-laptop 5: [dfc2b0c0] link (1fc2b0f0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead8) Jul 8 14:50:29 greg-laptop 6: [dfc2b0f0] link (1fc2b120) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae0) Jul 8 14:50:29 greg-laptop 7: [dfc2b120] link (1fc2b150) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae8) Jul 8 14:50:29 greg-laptop 8: [dfc2b150] link (1fc2b180) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf0) Jul 8 14:50:29 greg-laptop 9: [dfc2b180] link (1fc2b1b0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf8) Jul 8 14:50:29 greg-laptop 10: [dfc2b1b0] link (1fc2b210) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Dummy TD Jul 8 14:50:29 greg-laptop [dfc2b210] link (1fc2b240) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: uhci_result_control: failed with status 440000 Jul 8 14:50:29 greg-laptop [dfc2c380] link (1fc2c2c2) element (1fc2b210) Jul 8 14:50:29 greg-laptop urb_priv [f795d8a8] urb [f7ec93c0] qh [dfc2c380] Dev=0 EP=0(IN) CTL Jul 8 14:50:29 greg-laptop 1: [dfc2b210] link (1fc2b240) e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=3551cac0) Jul 8 14:50:29 greg-laptop 2: [dfc2b240] link (1fc2b270) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac0) Jul 8 14:50:29 greg-laptop 3: [dfc2b270] link (1fc2b2a0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac8) Jul 8 14:50:29 greg-laptop 4: [dfc2b2a0] link (1fc2b2d0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead0) Jul 8 14:50:29 greg-laptop 5: [dfc2b2d0] link (1fc2b300) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead8) Jul 8 14:50:29 greg-laptop 6: [dfc2b300] link (1fc2b330) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae0) Jul 8 14:50:29 greg-laptop 7: [dfc2b330] link (1fc2b360) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae8) Jul 8 14:50:29 greg-laptop 8: [dfc2b360] link (1fc2b390) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf0) Jul 8 14:50:29 greg-laptop 9: [dfc2b390] link (1fc2b3c0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf8) Jul 8 14:50:29 greg-laptop 10: [dfc2b3c0] link (1fc2b3f0) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Dummy TD Jul 8 14:50:29 greg-laptop [dfc2b3f0] link (1fc2b030) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: uhci_result_control: failed with status 440000 Jul 8 14:50:29 greg-laptop [dfc2c380] link (1fc2c2c2) element (1fc2b3f0) Jul 8 14:50:29 greg-laptop urb_priv [f795d8a8] urb [f7ec93c0] qh [dfc2c380] Dev=0 EP=0(IN) CTL Jul 8 14:50:29 greg-laptop 1: [dfc2b3f0] link (1fc2b030) e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=3551cac0) Jul 8 14:50:29 greg-laptop 2: [dfc2b030] link (1fc2b060) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac0) Jul 8 14:50:29 greg-laptop 3: [dfc2b060] link (1fc2b090) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac8) Jul 8 14:50:29 greg-laptop 4: [dfc2b090] link (1fc2b0c0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead0) Jul 8 14:50:29 greg-laptop 5: [dfc2b0c0] link (1fc2b0f0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead8) Jul 8 14:50:29 greg-laptop 6: [dfc2b0f0] link (1fc2b120) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae0) Jul 8 14:50:29 greg-laptop 7: [dfc2b120] link (1fc2b150) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae8) Jul 8 14:50:29 greg-laptop 8: [dfc2b150] link (1fc2b180) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf0) Jul 8 14:50:29 greg-laptop 9: [dfc2b180] link (1fc2b1b0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf8) Jul 8 14:50:29 greg-laptop 10: [dfc2b1b0] link (1fc2b1e0) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Dummy TD Jul 8 14:50:29 greg-laptop [dfc2b1e0] link (1fc2b030) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Jul 8 14:50:29 greg-laptop usb 2-1: device descriptor read/64, error -71 Jul 8 14:50:29 greg-laptop usb 2-1: new low speed USB device using uhci_hcd and address 3 Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: uhci_result_control: failed with status 440000 Jul 8 14:50:29 greg-laptop [dfc2c380] link (1fc2c2c2) element (1fc2b030) Jul 8 14:50:29 greg-laptop urb_priv [f795d8a8] urb [f7ec93c0] qh [dfc2c380] Dev=0 EP=0(IN) CTL Jul 8 14:50:29 greg-laptop 1: [dfc2b030] link (1fc2b060) e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=3551cac0) Jul 8 14:50:29 greg-laptop 2: [dfc2b060] link (1fc2b090) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac0) Jul 8 14:50:29 greg-laptop 3: [dfc2b090] link (1fc2b0c0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac8) Jul 8 14:50:29 greg-laptop 4: [dfc2b0c0] link (1fc2b0f0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead0) Jul 8 14:50:29 greg-laptop 5: [dfc2b0f0] link (1fc2b120) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead8) Jul 8 14:50:29 greg-laptop 6: [dfc2b120] link (1fc2b150) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae0) Jul 8 14:50:29 greg-laptop 7: [dfc2b150] link (1fc2b180) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae8) Jul 8 14:50:29 greg-laptop 8: [dfc2b180] link (1fc2b1b0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf0) Jul 8 14:50:29 greg-laptop 9: [dfc2b1b0] link (1fc2b1e0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf8) Jul 8 14:50:29 greg-laptop 10: [dfc2b1e0] link (1fc2b210) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Dummy TD Jul 8 14:50:29 greg-laptop [dfc2b210] link (1fc2b240) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: uhci_result_control: failed with status 440000 Jul 8 14:50:29 greg-laptop [dfc2c380] link (1fc2c2c2) element (1fc2b210) Jul 8 14:50:29 greg-laptop urb_priv [f795d8a8] urb [f7ec93c0] qh [dfc2c380] Dev=0 EP=0(IN) CTL Jul 8 14:50:29 greg-laptop 1: [dfc2b210] link (1fc2b240) e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=3551cac0) Jul 8 14:50:29 greg-laptop 2: [dfc2b240] link (1fc2b270) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac0) Jul 8 14:50:29 greg-laptop 3: [dfc2b270] link (1fc2b2a0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac8) Jul 8 14:50:29 greg-laptop 4: [dfc2b2a0] link (1fc2b2d0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead0) Jul 8 14:50:29 greg-laptop 5: [dfc2b2d0] link (1fc2b300) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead8) Jul 8 14:50:29 greg-laptop 6: [dfc2b300] link (1fc2b330) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae0) Jul 8 14:50:29 greg-laptop 7: [dfc2b330] link (1fc2b360) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae8) Jul 8 14:50:29 greg-laptop 8: [dfc2b360] link (1fc2b390) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf0) Jul 8 14:50:29 greg-laptop 9: [dfc2b390] link (1fc2b3c0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf8) Jul 8 14:50:29 greg-laptop 10: [dfc2b3c0] link (1fc2b3f0) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Dummy TD Jul 8 14:50:29 greg-laptop [dfc2b3f0] link (1fc2b030) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Jul 8 14:50:29 greg-laptop uhci_hcd 0000:00:1d.2: uhci_result_control: failed with status 440000 Jul 8 14:50:29 greg-laptop [dfc2c380] link (1fc2c2c2) element (1fc2b3f0) Jul 8 14:50:29 greg-laptop urb_priv [f795d8a8] urb [f7ec93c0] qh [dfc2c380] Dev=0 EP=0(IN) CTL Jul 8 14:50:29 greg-laptop 1: [dfc2b3f0] link (1fc2b030) e0 LS Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=0, PID=2d(SETUP) (buf=3551cac0) Jul 8 14:50:29 greg-laptop 2: [dfc2b030] link (1fc2b060) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac0) Jul 8 14:50:29 greg-laptop 3: [dfc2b060] link (1fc2b090) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eac8) Jul 8 14:50:29 greg-laptop 4: [dfc2b090] link (1fc2b0c0) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead0) Jul 8 14:50:29 greg-laptop 5: [dfc2b0c0] link (1fc2b0f0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7ead8) Jul 8 14:50:29 greg-laptop 6: [dfc2b0f0] link (1fc2b120) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae0) Jul 8 14:50:29 greg-laptop 7: [dfc2b120] link (1fc2b150) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eae8) Jul 8 14:50:29 greg-laptop 8: [dfc2b150] link (1fc2b180) e3 SPD LS Active Length=0 MaxLen=7 DT1 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf0) Jul 8 14:50:29 greg-laptop 9: [dfc2b180] link (1fc2b1b0) e3 SPD LS Active Length=0 MaxLen=7 DT0 EndPt=0 Dev=0, PID=69(IN) (buf=33d7eaf8) Jul 8 14:50:29 greg-laptop 10: [dfc2b1b0] link (1fc2b1e0) e3 LS IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Dummy TD Jul 8 14:50:29 greg-laptop [dfc2b1e0] link (1fc2b210) e0 Length=0 MaxLen=7ff DT0 EndPt=0 Dev=0, PID=e1(OUT) (buf=00000000) Jul 8 14:50:29 greg-laptop Jul 8 14:50:30 greg-laptop hub 2-0:1.0: state 7 ports 2 chg 0000 evt 0002 Jul 8 14:50:30 greg-laptop hub 2-0:1.0: state 7 ports 2 chg 0000 evt 0002 Jul 8 14:50:30 greg-laptop uhci_hcd 0000:00:1d.2: port 1 portsc 0093,00 Jul 8 14:50:30 greg-laptop hub 2-0:1.0: port 1, status 0101, change 0001, 12 Mb/s Jul 8 14:50:30 greg-laptop hub 2-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x101 Jul 8 14:50:30 greg-laptop usb 2-1: new full speed USB device using uhci_hcd and address 4 Jul 8 14:50:30 greg-laptop usb 2-1: ep0 maxpacket = 8 Jul 8 14:50:30 greg-laptop usb 2-1: skipped 4 descriptors after interface Jul 8 14:50:30 greg-laptop usb 2-1: skipped 4 descriptors after interface Jul 8 14:50:30 greg-laptop usb 2-1: default language 0x0409 Jul 8 14:50:30 greg-laptop usb 2-1: new device strings: Mfr=1, Product=2, SerialNumber=0 Jul 8 14:50:30 greg-laptop usb 2-1: Product: Motorola Phone (V3) Jul 8 14:50:30 greg-laptop usb 2-1: Manufacturer: Motorola Inc. Jul 8 14:50:30 greg-laptop usb 2-1: uevent Jul 8 14:50:30 greg-laptop usb 2-1: configuration #1 chosen from 2 choices Jul 8 14:50:30 greg-laptop usb 2-1: adding 2-1:1.0 (config #1, interface 0) Jul 8 14:50:30 greg-laptop usb 2-1:1.0: uevent Jul 8 14:50:30 greg-laptop usb 2-1: adding 2-1:1.1 (config #1, interface 1) Jul 8 14:50:30 greg-laptop usb 2-1:1.1: uevent Jul 8 14:50:30 greg-laptop drivers/usb/core/inode.c: creating file '004' Jul 8 14:50:30 greg-laptop hub 2-0:1.0: state 7 ports 2 chg 0000 evt 0002 Jul 8 14:50:30 greg-laptop usb.agent[10281]: Keeping default configuration with /sys//devices/pci0000:00/0000:00:1d.2/usb2/2-1 Jul 8 14:50:32 greg-laptop cdc_acm 2-1:1.0: usb_probe_interface Jul 8 14:50:32 greg-laptop cdc_acm 2-1:1.0: usb_probe_interface - got id Jul 8 14:50:32 greg-laptop cdc_acm 2-1:1.0: ttyACM0: USB ACM device Jul 8 14:50:32 greg-laptop usbcore: registered new driver cdc_acm Jul 8 14:50:32 greg-laptop drivers/usb/class/cdc-acm.c: v0.25:USB Abstract Control Model driver for USB modems and ISDN adapters Jul 8 14:50:36 greg-laptop hub 2-0:1.0: state 7 ports 2 chg 0000 evt 0002 Jul 8 14:50:36 greg-laptop uhci_hcd 0000:00:1d.2: port 1 portsc 00b9,00 Jul 8 14:50:36 greg-laptop hub 2-0:1.0: port 1 enable change, status 00000101 Jul 8 14:50:36 greg-laptop hub 2-0:1.0: port 1 disabled by hub (EMI?), re-enabling... Jul 8 14:50:36 greg-laptop hub 2-0:1.0: port 1, status 0101, change 0002, 12 Mb/s Jul 8 14:50:36 greg-laptop usb 2-1: USB disconnect, address 4 Jul 8 14:50:36 greg-laptop usb 2-1: usb_disable_device nuking all URBs Jul 8 14:50:36 greg-laptop usb 2-1: unregistering interface 2-1:1.0 Jul 8 14:50:36 greg-laptop usb 2-1:1.0: uevent Jul 8 14:50:36 greg-laptop usb 2-1: unregistering interface 2-1:1.1 Jul 8 14:50:36 greg-laptop usb 2-1:1.1: uevent Jul 8 14:50:36 greg-laptop usb 2-1: unregistering device Jul 8 14:50:36 greg-laptop usb 2-1: uevent Jul 8 14:50:37 greg-laptop uhci_hcd 0000:00:1d.2: suspend_rh (auto-stop) Jul 8 14:51:06 greg-laptop uhci_hcd 0000:00:1d.2: wakeup_rh (auto-start) Jul 8 14:51:06 greg-laptop hub 2-0:1.0: state 7 ports 2 chg 0000 evt 0002 Jul 8 14:51:06 greg-laptop uhci_hcd 0000:00:1d.2: port 1 portsc 01a3,00 Jul 8 14:51:06 greg-laptop hub 2-0:1.0: port 1, status 0301, change 0001, 1.5 Mb/s Jul 8 14:51:06 greg-laptop uhci_hcd 0000:00:1d.2: port 1 portsc 0082,00 Jul 8 14:51:06 greg-laptop hub 2-0:1.0: debounce: port 1: total 125ms stable 100ms status 0x100 Jul 8 14:51:07 greg-laptop uhci_hcd 0000:00:1d.2: suspend_rh (auto-stop)
Am closing due to lack of response. If this still happens, with the proposed patch on the latest kernel version, please reopen the bug with and post the error logs.
I'm seeing this with 2.6.22.1 while trying to talk to a gumstix. [93972.822392] usb 3-2: new full speed USB device using uhci_hcd and address 3 [93972.946234] usb 3-2: device descriptor read/64, error -71 [93973.172169] usb 3-2: device descriptor read/64, error -71 [93973.389696] usb 3-2: new full speed USB device using uhci_hcd and address 4 [93973.511733] usb 3-2: device descriptor read/64, error -71 [93973.735449] usb 3-2: device descriptor read/64, error -71 [93973.951180] usb 3-2: new full speed USB device using uhci_hcd and address 5 [93974.360529] usb 3-2: device not accepting address 5, error -71 [93974.470526] usb 3-2: new full speed USB device using uhci_hcd and address 6 [93974.877998] usb 3-2: device not accepting address 6, error -71 [93979.977061] usb 3-2: new full speed USB device using uhci_hcd and address 7 [93980.099643] usb 3-2: device descriptor read/64, error -71 [93980.327365] usb 3-2: device descriptor read/64, error -71 [93980.540353] usb 3-2: new full speed USB device using uhci_hcd and address 8 [93980.660196] usb 3-2: device descriptor read/64, error -71 [93980.887913] usb 3-2: device descriptor read/64, error -71 [93981.106438] usb 3-2: new full speed USB device using uhci_hcd and address 9 [93981.513937] usb 3-2: device not accepting address 9, error -71 [93981.622985] usb 3-2: new full speed USB device using uhci_hcd and address 10 [93982.033305] usb 3-2: device not accepting address 10, error -71 Same behavior with two different machines.
Sounds like it could be a wiring problem on the gumstix, or something of that sort.
I'm seeing this problem with my Scanjet 5300C on Ubuntu 7.04 with 2.6.20-16. I'll try the patch from David Brownell, and let people know if this is works. This may take a while, as I've never built a kernel under Ubuntu before :)
Nope, the David's patch doesn't fix my issue. There's nothing new in the logs that I can find, but if i boot in Ubuntu's 'recovery mode', I get extra "device not accepting address X, error -71" messages echo'd to console. lsusb doesn't show the scanner, but is frequently slow... I guess this is because it has to wait for the bus reset to finish. e.g. : phil@Ubuntu:~/kernel$ /usr/bin/time --format="%E" lsusb Bus 002 Device 001: ID 0000:0000 Bus 001 Device 002: ID 046d:08f6 Logitech, Inc. Bus 001 Device 001: ID 0000:0000 0:10.51 phil@Ubuntu:~/kernel$ /usr/bin/time --format="%E" lsusb Bus 002 Device 001: ID 0000:0000 Bus 001 Device 002: ID 046d:08f6 Logitech, Inc. Bus 001 Device 001: ID 0000:0000 0:10.53 phil@Ubuntu:~/kernel$ /usr/bin/time --format="%E" lsusb Bus 002 Device 001: ID 0000:0000 Bus 001 Device 011: ID 03f0:0701 Hewlett-Packard ScanJet 5300c/5370c Bus 001 Device 002: ID 046d:08f6 Logitech, Inc. Bus 001 Device 001: ID 0000:0000 0:00.11 phil@Ubuntu:~/kernel$ /usr/bin/time --format="%E" lsusb Bus 002 Device 001: ID 0000:0000 Bus 001 Device 002: ID 046d:08f6 Logitech, Inc. Bus 001 Device 001: ID 0000:0000 0:10.54 I've not done any kernel debugging before, so please provide hints on anything else useful I can do.
Hmm... just read my own last update and spotted that the scanner actually got picked up once, then disappeared. I've downloaded some USB docs, and will go through this... as a pure, ill-educated guess, it could be that the SET_ADDRESS is being timed out by the stack, then takes effect on the device, and then getting clobbered by the hub reset.
The SET_ADDRESS problem is fixed in the 2.6.22 kernel. I can get a preview scan in sane fine, but for some reason it a full scan fails. This may well be a problem in either sane or libusb, and even if its a usb-kernel bug, it is unlikely to be related to the SET_ADDRESS problem.
Also see http://qa.mandriva.com/show_bug.cgi?id=26728#c41, which includes apparently related information (talking specifically about HP5300C/HP5370C scanners with apparently broken USB protocol) and which includes a patch.