On Tue, Feb 22, 2005 at 10:18:10AM -0800, Pete Zaitcev wrote: > I thought at first that it's something that ub does. But apparently > Glenn sees it with usb-storage as well. I cannot tell if this is > the same thing, but it looks like a case of interference between > the storage device and someone who's reading descriptors (udev in > this case).
Well, I'm glad I didn't switch our system over to udev ... :) With the previous busy-loop-over-the-serial script and ub (and uhci_hcd debug=3): 6453.182260: <7>uhci_hcd 0000:00:1d.1: wakeup_hc 6453.285277: <7>hub 2-0:1.0: state 5 ports 2 chg 0000 evt 0004 6453.285349: <7>uhci_hcd 0000:00:1d.1: port 2 portsc 0083,00 6453.285375: <7>hub 2-0:1.0: port 2, status 0101, change 0001, 12 Mb/s 6453.390233: <7>hub 2-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x101 6453.510254: <6>usb 2-2: new full speed USB device using uhci_hcd and address 4 6453.672219: <7>usb 2-2: new device strings: Mfr=1, Product=2, SerialNumber=3 6453.675209: <7>usb 2-2: default language 0x0409 6453.679231: <6>usb 2-2: Product: USB DISK 12X 6453.684170: <6>usb 2-2: Manufacturer: 6453.688329: <6>usb 2-2: SerialNumber: 07410E1A052F 6453.688403: <7>usb 2-2: hotplug 6453.689319: <7>usb 2-2: adding 2-2:1.0 (config #1, interface 0) 6453.689397: <7>usb 2-2:1.0: hotplug 6453.689422: <7>ub 2-2:1.0: usb_probe_interface 6453.689443: <7>ub 2-2:1.0: usb_probe_interface - got id 6454.265253: <6>uba: device 4 capacity nsec 243712 bsize 512 6454.272217: <6>uba: device 4 capacity nsec 243712 bsize 512 6459.274352: <6> uba:end_request: I/O error, dev uba, sector 0 6459.274429: <3>Buffer I/O error on device uba, logical block 0 6459.274648: <4>usb 2-2: cat timed out on ep0in 6464.275595: <4>end_request: I/O error, dev uba, sector 0 6464.275667: <3>Buffer I/O error on device uba, logical block 0 6464.275693: <4> unable to read partition table 6464.275715: <7>hub 2-0:1.0: state 5 ports 2 chg 0000 evt 0004 6464.275922: <4>usb 2-2: cat timed out on ep0in 6469.277615: <4>usb 2-2: cat timed out on ep0in 6474.278773: <4>usb 2-2: cat timed out on ep0in cat timeout continues indefinitely until the device is removed, then: 6534.291769: <4>usb 2-2: cat timed out on ep0in 6538.763098: <7>uhci_hcd 0000:00:1d.1: uhci_result_control: failed with status 440000 6538.763174: <7>[de405240] link (1e4051e2) element (1eb38040) 6538.763221: <7> Element != First TD 6538.763244: <7> 0: [deb38000] link (1eb38040) e3 Length=7 MaxLen=7 DT0 EndPt=0 Dev=4, PID=2d(SETUP) (buf=1f14c540) 6538.763267: <7> 1: [deb38040] link (1eb38080) e0 SPD Stalled CRC/Timeo Length=7ff MaxLen=3f DT1 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e880) 6538.763289: <7> 2: [deb38080] link (1eb380c0) e3 SPD Active Length=0 MaxLen=3f DT0 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e8c0) 6538.763311: <7> 3: [deb380c0] link (1eb38100) e3 SPD Active Length=0 MaxLen=3f DT1 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e900) 6538.763334: <7> 4: [deb38100] link (1eb38144) e3 SPD Active Length=0 MaxLen=3e DT0 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e940) 6538.763356: <7> 5: [deb38140] link (00000001) e3 IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=4, PID=e1(OUT) (buf=00000000) 6538.763377: <7> 6538.765046: <7>uhci_hcd 0000:00:1d.1: uhci_result_control: failed with status 440000 6538.765105: <7>[de405270] link (1e4051e2) element (1eb38180) 6538.765130: <7> 0: [deb38180] link (1eb381c0) e0 Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=4, PID=2d(SETUP) (buf=1f14c540) 6538.765154: <7> 1: [deb381c0] link (1eb38200) e3 SPD Active Length=0 MaxLen=1 DT1 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e880) 6538.765176: <7> 2: [deb38200] link (00000001) e3 IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=4, PID=e1(OUT) (buf=00000000) 6538.765361: <7> 6538.768096: <7>uhci_hcd 0000:00:1d.1: uhci_result_control: failed with status 440000 6538.768175: <7>[de405240] link (1e4051e2) element (1eb38000) 6538.768220: <7> 0: [deb38000] link (1eb38040) e0 Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=4, PID=2d(SETUP) (buf=1f14c540) 6538.768247: <7> 1: [deb38040] link (1eb38080) e3 SPD Active Length=0 MaxLen=3f DT1 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e880) 6538.768270: <7> 2: [deb38080] link (1eb380c0) e3 SPD Active Length=0 MaxLen=3f DT0 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e8c0) 6538.768293: <7> 3: [deb380c0] link (1eb38100) e3 SPD Active Length=0 MaxLen=3f DT1 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e900) 6538.768315: <7> 4: [deb38100] link (1eb38140) e3 SPD Active Length=0 MaxLen=3e DT0 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e940) 6538.768337: <7> 5: [deb38140] link (00000001) e3 IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=4, PID=e1(OUT) (buf=00000000) 6538.768359: <7> 6538.769046: <7>uhci_hcd 0000:00:1d.1: uhci_result_control: failed with status 440000 6538.769103: <7>[de405270] link (1e4051e2) element (1eb38180) 6538.769128: <7> 0: [deb38180] link (1eb381c0) e0 Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=4, PID=2d(SETUP) (buf=1f14c540) 6538.769153: <7> 1: [deb381c0] link (1eb38200) e3 SPD Active Length=0 MaxLen=1 DT1 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e880) 6538.769176: <7> 2: [deb38200] link (00000001) e3 IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=4, PID=e1(OUT) (buf=00000000) 6538.769223: <7> 6538.771097: <7>uhci_hcd 0000:00:1d.1: uhci_result_control: failed with status 440000 6538.771175: <7>[de405240] link (1e4051e2) element (1eb38000) 6538.771222: <7> 0: [deb38000] link (1eb38040) e0 Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=4, PID=2d(SETUP) (buf=1f14c540) 6538.771248: <7> 1: [deb38040] link (1eb38080) e3 SPD Active Length=0 MaxLen=3f DT1 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e880) 6538.771270: <7> 2: [deb38080] link (1eb380c0) e3 SPD Active Length=0 MaxLen=3f DT0 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e8c0) 6538.771292: <7> 3: [deb380c0] link (1eb38100) e3 SPD Active Length=0 MaxLen=3f DT1 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e900) 6538.771314: <7> 4: [deb38100] link (1eb38140) e3 SPD Active Length=0 MaxLen=3e DT0 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e940) 6538.771337: <7> 5: [deb38140] link (00000001) e3 IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=4, PID=e1(OUT) (buf=00000000) 6538.771359: <7> 6538.772045: <7>uhci_hcd 0000:00:1d.1: uhci_result_control: failed with status 440000 6538.772102: <7>[de405270] link (1e4051e2) element (1eb38180) 6538.772128: <7> 0: [deb38180] link (1eb381c0) e0 Stalled CRC/Timeo Length=7 MaxLen=7 DT0 EndPt=0 Dev=4, PID=2d(SETUP) (buf=1f14c540) 6538.772151: <7> 1: [deb381c0] link (1eb38200) e3 SPD Active Length=0 MaxLen=1 DT1 EndPt=0 Dev=4, PID=69(IN) (buf=1e97e880) 6538.772174: <7> 2: [deb38200] link (00000001) e3 IOC Active Length=0 MaxLen=7ff DT1 EndPt=0 Dev=4, PID=e1(OUT) (buf=00000000) 6538.772535: <7> 6538.772615: <7>hub 2-0:1.0: state 5 ports 2 chg 0000 evt 0004 6538.772641: <7>uhci_hcd 0000:00:1d.1: port 2 portsc 008a,00 6538.772664: <7>hub 2-0:1.0: port 2, status 0100, change 0003, 12 Mb/s 6538.772687: <6>usb 2-2: USB disconnect, address 4 6538.772709: <7>usb 2-2: usb_disable_device nuking all URBs 6538.772731: <7>usb 2-2: unregistering interface 2-2:1.0 6538.772751: <7>usb 2-2:1.0: hotplug 6538.772771: <7>usb 2-2: unregistering device 6538.772792: <7>usb 2-2: hotplug 6538.876244: <7>hub 2-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100 6539.769093: <7>uhci_hcd 0000:00:1d.1: suspend_hc and cat shows the serial 8-10 times. At one point, I also had problems with lsusb -v interfering with communications to our USB I/O device: we'd occasionally lose an input with an error. lsusb is similarly querying the device strings. This isn't a problem now (sysfs lets us avoid touching devices we don't want to query), but it's strange for just running lsusb to cause problems (or time out, if a device isn't responding). -- Glenn Maynard ------------------------------------------------------- SF email is sponsored by - The IT Product Guide Read honest & candid reviews on hundreds of IT Products from real users. Discover which products truly live up to the hype. Start reading now. http://ads.osdn.com/?ad_id=6595&alloc_id=14396&op=click _______________________________________________ [email protected] To unsubscribe, use the last form field at: https://lists.sourceforge.net/lists/listinfo/linux-usb-devel
