On 05/31/2011 11:38 AM, Alan Stern wrote:
> Please configure your email client to wrap lines after 72 columns or
> so.
Hopefully it's correct now.
>
> Do you have any pointers to the discussion leading to that update or to
> the particular code change that fixed the problem?
Sorry, I only have the package archive on this machine to hint at when new kernels where installed, along with notes collected while debugging this UPS issue last summer.
>
> Actually it's a lot easier to read output from usbmon's text interface
> than wireshark output. See the instructions in
> Documentation/usb/usbmon.txt.
cat .../3u data attached.
>
> The reason for the disconnects is not obvious. If it was already
> figured out and fixed, it would be a big help to know what the solution
> was (and why it is no longer working).
I also applied a patch to the nut driver (network ups tools) that was also required to make it work (summer 2010). This patch had to do with low-speed devices having a maximum packet size of 8 bytes.
I tried to interpret the usbmon output to determine if descriptor requests, etc, where limited to 8 byte buffers. It does show a Malformed Packet at the end of Frame 2. (I have this textual tshark output too, but it's 625K file, first two frames below)
As an aside, I had this exact sequence of events with a FreeBSD server with this same UPS model: broken in 7.1, fixed with update to 7.3, broken again with recent update to 8.1. (Broken in same way, repeated disconnect/reconnect)
>
> Alan Stern
Thank you Alan!
Please let me know if there's anyway for me to gather more specific details of the disconnect/reconnect activity.
johnea
########## First 2 Frames of tshark -V -i usbmon3 > file.txt ##########
Frame 1: 64 bytes on wire (512 bits), 64 bytes captured (512 bits)
Arrival Time: May 28, 2011 17:07:49.567384000 PDT
Epoch Time: 1306627669.567384000 seconds
[Time delta from previous captured frame: 0.000000000 seconds]
[Time delta from previous displayed frame: 0.000000000 seconds]
[Time since reference or first frame: 0.000000000 seconds]
Frame Number: 1
Frame Length: 64 bytes (512 bits)
Capture Length: 64 bytes (512 bits)
[Frame is marked: False]
[Frame is ignored: False]
[Protocols in frame: usb]
USB URB
URB id: 0x00000000f2b90a80
URB type: URB_SUBMIT ('S')
URB transfer type: URB_CONTROL out (0x02)
Endpoint: 0x80
Device: 82
URB bus id: 3
Device setup request: relevant (0)
Data: not present ('<')
URB sec: 1306627669
URB usec: 567384
URB status: Operation now in progress (-EINPROGRESS) (-115)
URB length [bytes]: 40
Data length [bytes]: 0
[bInterfaceClass: Unknown (0xffff)]
URB setup
bmRequestType: 0x80
1... .... = Direction: Device-to-host
.00. .... = Type: Standard (0x00)
...0 0000 = Recipient: Device (0x00)
bRequest: GET DESCRIPTOR (6)
Descriptor Index: 0x00
bDescriptorType: DEVICE (1)
Language Id: no language specified (0x0000)
wLength: 40
Frame 2: 64 bytes on wire (512 bits), 64 bytes captured (512 bits)
Arrival Time: May 28, 2011 17:07:49.570858000 PDT
Epoch Time: 1306627669.570858000 seconds
[Time delta from previous captured frame: 0.003474000 seconds]
[Time delta from previous displayed frame: 0.003474000 seconds]
[Time since reference or first frame: 0.003474000 seconds]
Frame Number: 2
Frame Length: 64 bytes (512 bits)
Capture Length: 64 bytes (512 bits)
[Frame is marked: False]
[Frame is ignored: False]
[Protocols in frame: usb]
USB URB
URB id: 0x00000000f2b90a80
URB type: URB_COMPLETE ('C')
URB transfer type: URB_CONTROL out (0x02)
Endpoint: 0x80
Device: 82
URB bus id: 3
Device setup request: not relevant ('-')
Data: present (0)
URB sec: 1306627669
URB usec: 570858
URB status: Protocol error (-EPROTO) (-71)
URB length [bytes]: 0
Data length [bytes]: 0
[Request in: 1]
[Time from request: 0.003474000 seconds]
[bInterfaceClass: Unknown (0xffff)]
[Malformed Packet: USB]
[Expert Info (Error/Malformed): Malformed Packet (Exception occurred)]
[Message: Malformed Packet (Exception occurred)]
[Severity level: Error]
[Group: Malformed]
f40a8200 3201734542 C Ii:3:001:1 0:128 1 = 02
f40a8200 3201734551 S Ii:3:001:1 -115:128 2 <
f4908880 3201734563 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4908880 3201734572 C Ci:3:001:0 0 4 = 01030300
f4908880 3201734575 S Co:3:001:0 s 23 01 0010 0001 0000 0
f4908880 3201734578 C Co:3:001:0 0 0
f4908880 3201734579 S Co:3:001:0 s 23 01 0011 0001 0000 0
f4908880 3201734582 C Co:3:001:0 0 0
f4908880 3201734855 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4908880 3201734868 C Ci:3:001:0 0 4 = 01030000
f4908a80 3201764554 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4908a80 3201764562 C Ci:3:001:0 0 4 = 01030000
f4908a80 3201794542 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4908a80 3201794549 C Ci:3:001:0 0 4 = 01030000
f4908a80 3201824539 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4908a80 3201824546 C Ci:3:001:0 0 4 = 01030000
f4908a80 3201854545 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4908a80 3201854555 C Ci:3:001:0 0 4 = 01030000
f4908a80 3201854563 S Co:3:001:0 s 23 03 0004 0001 0000 0
f4908a80 3201854566 C Co:3:001:0 0 0
f4908a80 3201907880 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4908a80 3201907905 C Ci:3:001:0 0 4 = 03030000
f4908a80 3201961212 S Co:3:001:0 s 23 01 0014 0001 0000 0
f4908a80 3201961218 C Co:3:001:0 0 0
f4908a80 3201961226 S Ci:3:000:0 s 80 06 0100 0000 0040 64 <
f4908a80 3201968066 C Ci:3:000:0 0 18 = 12011001 00000008 64070105 01000301 0001
f4908a80 3201968091 S Co:3:001:0 s 23 03 0004 0001 0000 0
f4908a80 3201968096 C Co:3:001:0 0 0
f40a8200 3201984549 C Ii:3:001:1 0:128 1 = 02
f40a8200 3201984553 S Ii:3:001:1 -115:128 2 <
f4908a80 3202021207 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4908a80 3202021229 C Ci:3:001:0 0 4 = 03030000
f4908a80 3202074550 S Co:3:001:0 s 23 01 0014 0001 0000 0
f4908a80 3202074554 C Co:3:001:0 0 0
f4908a80 3202074556 S Co:3:000:0 s 00 05 0042 0000 0000 0
f4908a80 3202077058 C Co:3:000:0 0 0
f4908a80 3202091206 S Ci:3:066:0 s 80 06 0100 0000 0012 18 <
f4908a80 3202097057 C Ci:3:066:0 0 18 = 12011001 00000008 64070105 01000301 0001
f4908a80 3202097073 S Ci:3:066:0 s 80 06 0200 0000 0009 9 <
f4908a80 3202102057 C Ci:3:066:0 0 9 = 09022200 01010080 19
f4908a80 3202102082 S Ci:3:066:0 s 80 06 0200 0000 0022 34 <
f4908a80 3202110057 C Ci:3:066:0 0 34 = 09022200 01010080 19090400 00010300 00000921 10012101 22780107 05810308
f4908a80 3202110070 S Ci:3:066:0 s 80 06 0300 0000 00ff 255 <
f4908a80 3202115057 C Ci:3:066:0 0 4 = 04030904
f4908a80 3202115071 S Ci:3:066:0 s 80 06 0301 0409 00ff 255 <
f4908a80 3202122057 C Ci:3:066:0 0 16 = 10034300 50003400 32003500 48004700
f4908a80 3202122080 S Ci:3:066:0 s 80 06 0303 0409 00ff 255 <
f4908a80 3202128057 C Ci:3:066:0 0 8 = 08034300 50005300
f4908a80 3202128171 S Co:3:066:0 s 00 09 0001 0000 0000 0
f4908a80 3202131056 C Co:3:066:0 0 0
f4908a80 3202131165 S Co:3:066:0 s 21 0a 0000 0000 0000 0
f4908a80 3202134055 C Co:3:066:0 -32 0
f4908a80 3202134067 S Ci:3:066:0 s 81 06 2200 0000 0178 376 <
f4908a80 3202184060 C Ci:3:066:0 0 376 = 05840904 a1010924 a1008501 09fe7508 95011500 26ff00b1 22850209 ffb12285
f43ac880 3202185952 S Ci:3:066:0 s a1 01 0108 0000 0006 8 <
f43ac880 3202189054 C Ci:3:066:0 0 6 = 08649204 2c01
f43ac880 3202189069 S Ci:3:066:0 s a1 01 010b 0000 0002 8 <
f43ac880 3202193054 C Ci:3:066:0 0 2 = 0b11
f43ac880 3202193071 S Ci:3:066:0 s a1 01 0301 0000 0002 8 <
f43ac880 3202197054 C Ci:3:066:0 0 2 = 0101
f43ac880 3202197065 S Ci:3:066:0 s a1 01 0302 0000 0002 8 <
f43ac880 3202201053 C Ci:3:066:0 0 2 = 0202
f43ac880 3202201061 S Ci:3:066:0 s a1 01 0303 0000 0002 8 <
f43ac880 3202205056 C Ci:3:066:0 0 2 = 0304
f43ac880 3202205064 S Ci:3:066:0 s a1 01 0304 0000 0002 8 <
f43ac880 3202209054 C Ci:3:066:0 0 2 = 0403
f43ac880 3202209061 S Ci:3:066:0 s a1 01 0305 0000 0002 8 <
f43ac880 3202213053 C Ci:3:066:0 0 2 = 0501
f43ac880 3202213060 S Ci:3:066:0 s a1 01 0306 0000 0002 8 <
f43ac880 3202217054 C Ci:3:066:0 0 2 = 0602
f43ac880 3202217062 S Ci:3:066:0 s a1 01 0307 0000 0007 8 <
f43ac880 3202221053 C Ci:3:066:0 0 7 = 0764050a 140a64
f43ac880 3202221069 S Ci:3:066:0 s a1 01 0308 0000 0006 8 <
f43ac880 3202225055 C Ci:3:066:0 0 6 = 08649204 2c01
f43ac880 3202225068 S Ci:3:066:0 s a1 01 0309 0000 0002 8 <
f43ac880 3202229054 C Ci:3:066:0 0 2 = 0978
f43ac880 3202229061 S Ci:3:066:0 s a1 01 030a 0000 0002 8 <
f43ac880 3202233053 C Ci:3:066:0 0 2 = 0a49
f43ac880 3202233059 S Ci:3:066:0 s a1 01 030b 0000 0002 8 <
f43ac880 3202237052 C Ci:3:066:0 0 2 = 0b11
f43ac880 3202237070 S Ci:3:066:0 s a1 01 030c 0000 0002 8 <
f43ac880 3202241052 C Ci:3:066:0 0 2 = 0c02
f43ac880 3202241059 S Ci:3:066:0 s a1 01 030d 0000 0002 8 <
f43ac880 3202245054 C Ci:3:066:0 0 2 = 0d03
f43ac880 3202245061 S Ci:3:066:0 s a1 01 030e 0000 0003 8 <
f43ac880 3202249053 C Ci:3:066:0 0 3 = 0e7800
f43ac880 3202249060 S Ci:3:066:0 s a1 01 030f 0000 0003 8 <
f43ac880 3202253052 C Ci:3:066:0 0 3 = 0f7500
f43ac880 3202253059 S Ci:3:066:0 s a1 01 0310 0000 0005 8 <
f43ac880 3202257053 C Ci:3:066:0 0 5 = 1060008c 00
f43ac880 3202257062 S Ci:3:066:0 s a1 01 0312 0000 0003 8 <
f43ac880 3202261052 C Ci:3:066:0 0 3 = 127600
f43ac880 3202261059 S Ci:3:066:0 s a1 01 0313 0000 0002 8 <
f43ac880 3202265053 C Ci:3:066:0 0 2 = 1318
f43ac880 3202265061 S Ci:3:066:0 s a1 01 0314 0000 0002 8 <
f43ac880 3202269052 C Ci:3:066:0 0 2 = 1400
f43ac880 3202269059 S Ci:3:066:0 s a1 01 0315 0000 0003 8 <
f43ac880 3202273052 C Ci:3:066:0 0 3 = 15ffff
f43ac880 3202273058 S Ci:3:066:0 s a1 01 0316 0000 0003 8 <
f43ac880 3202277052 C Ci:3:066:0 0 3 = 160000
f43ac880 3202277059 S Ci:3:066:0 s a1 01 0318 0000 0003 8 <
f43ac880 3202281051 C Ci:3:066:0 0 3 = 18ff00
f43ace80 3202281313 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f43ace80 3202281326 C Ci:3:001:0 0 4 = 03030000
f40a8200 3222984537 C Ii:3:001:1 0:128 1 = 02
f40a8200 3222984544 S Ii:3:001:1 -115:128 2 <
f4357980 3222984555 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3222984562 C Ci:3:001:0 0 4 = 00010300
f4357980 3222984565 S Co:3:001:0 s 23 01 0010 0001 0000 0
f4357980 3222984568 C Co:3:001:0 0 0
f4357980 3222984569 S Co:3:001:0 s 23 01 0011 0001 0000 0
f4357980 3222984572 C Co:3:001:0 0 0
f4357980 3222984849 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3222984863 C Ci:3:001:0 0 4 = 00010000
f4357980 3223014548 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3223014556 C Ci:3:001:0 0 4 = 00010000
f4357980 3223044552 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3223044560 C Ci:3:001:0 0 4 = 00010000
f4357980 3223074548 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3223074558 C Ci:3:001:0 0 4 = 00010000
f4357980 3223104543 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3223104553 C Ci:3:001:0 0 4 = 00010000
f40a8200 3223734549 C Ii:3:001:1 0:128 1 = 02
f40a8200 3223734554 S Ii:3:001:1 -115:128 2 <
f4357980 3223734562 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3223734566 C Ci:3:001:0 0 4 = 01030100
f4357980 3223734567 S Co:3:001:0 s 23 01 0010 0001 0000 0
f4357980 3223734570 C Co:3:001:0 0 0
f4357980 3223734572 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3223734575 C Ci:3:001:0 0 4 = 01030000
f4357980 3223764546 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3223764555 C Ci:3:001:0 0 4 = 01030000
f4357980 3223794546 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3223794553 C Ci:3:001:0 0 4 = 01030000
f4357980 3223824537 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3223824544 C Ci:3:001:0 0 4 = 01030000
f4357980 3223854543 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3223854550 C Ci:3:001:0 0 4 = 01030000
f4357980 3223854556 S Co:3:001:0 s 23 03 0004 0001 0000 0
f4357980 3223854561 C Co:3:001:0 0 0
f4357980 3223907877 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3223907898 C Ci:3:001:0 0 4 = 03030000
f4357980 3223961209 S Co:3:001:0 s 23 01 0014 0001 0000 0
f4357980 3223961212 C Co:3:001:0 0 0
f4357980 3223961220 S Ci:3:000:0 s 80 06 0100 0000 0040 64 <
f4357980 3223967476 C Ci:3:000:0 0 18 = 12011001 00000008 64070105 01000301 0001
f4357980 3223967487 S Co:3:001:0 s 23 03 0004 0001 0000 0
f4357980 3223967492 C Co:3:001:0 0 0
f40a8200 3223984548 C Ii:3:001:1 0:128 1 = 02
f40a8200 3223984552 S Ii:3:001:1 -115:128 2 <
f4357980 3224017875 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f4357980 3224017897 C Ci:3:001:0 0 4 = 03030000
f4357980 3224071209 S Co:3:001:0 s 23 01 0014 0001 0000 0
f4357980 3224071212 C Co:3:001:0 0 0
f4357980 3224071215 S Co:3:000:0 s 00 05 0043 0000 0000 0
f4357980 3224073473 C Co:3:000:0 0 0
f416c500 3224087879 S Ci:3:067:0 s 80 06 0100 0000 0012 18 <
f416c500 3224093470 C Ci:3:067:0 0 18 = 12011001 00000008 64070105 01000301 0001
f416c500 3224093484 S Ci:3:067:0 s 80 06 0200 0000 0009 9 <
f416c500 3224098471 C Ci:3:067:0 0 9 = 09022200 01010080 19
f416c500 3224098481 S Ci:3:067:0 s 80 06 0200 0000 0022 34 <
f416c500 3224106471 C Ci:3:067:0 0 34 = 09022200 01010080 19090400 00010300 00000921 10012101 22780107 05810308
f416c500 3224106486 S Ci:3:067:0 s 80 06 0300 0000 00ff 255 <
f416c500 3224111470 C Ci:3:067:0 0 4 = 04030904
f416c500 3224111483 S Ci:3:067:0 s 80 06 0301 0409 00ff 255 <
f416c500 3224118471 C Ci:3:067:0 0 16 = 10034300 50003400 32003500 48004700
f416c500 3224118481 S Ci:3:067:0 s 80 06 0303 0409 00ff 255 <
f416c500 3224124470 C Ci:3:067:0 0 8 = 08034300 50005300
f416c500 3224124605 S Co:3:067:0 s 00 09 0001 0000 0000 0
f416c500 3224127469 C Co:3:067:0 0 0
f416c500 3224127570 S Co:3:067:0 s 21 0a 0000 0000 0000 0
f416c500 3224130469 C Co:3:067:0 -32 0
f416c500 3224130478 S Ci:3:067:0 s 81 06 2200 0000 0178 376 <
f416c500 3224180473 C Ci:3:067:0 0 376 = 05840904 a1010924 a1008501 09fe7508 95011500 26ff00b1 22850209 ffb12285
f7106180 3224182361 S Ci:3:067:0 s a1 01 0108 0000 0006 8 <
f7106180 3224185467 C Ci:3:067:0 0 6 = 08649204 2c01
f7106180 3224185483 S Ci:3:067:0 s a1 01 010b 0000 0002 8 <
f7106180 3224189467 C Ci:3:067:0 0 2 = 0b11
f7106180 3224189485 S Ci:3:067:0 s a1 01 0301 0000 0002 8 <
f7106180 3224193468 C Ci:3:067:0 0 2 = 0101
f7106180 3224193478 S Ci:3:067:0 s a1 01 0302 0000 0002 8 <
f7106180 3224197467 C Ci:3:067:0 0 2 = 0202
f7106180 3224197475 S Ci:3:067:0 s a1 01 0303 0000 0002 8 <
f7106180 3224201469 C Ci:3:067:0 0 2 = 0304
f7106180 3224201477 S Ci:3:067:0 s a1 01 0304 0000 0002 8 <
f7106180 3224205468 C Ci:3:067:0 0 2 = 0403
f7106180 3224205475 S Ci:3:067:0 s a1 01 0305 0000 0002 8 <
f7106180 3224209468 C Ci:3:067:0 0 2 = 0501
f7106180 3224209475 S Ci:3:067:0 s a1 01 0306 0000 0002 8 <
f7106180 3224213468 C Ci:3:067:0 0 2 = 0602
f7106180 3224213475 S Ci:3:067:0 s a1 01 0307 0000 0007 8 <
f7106180 3224217466 C Ci:3:067:0 0 7 = 0764050a 140a64
f7106180 3224217483 S Ci:3:067:0 s a1 01 0308 0000 0006 8 <
f7106180 3224221468 C Ci:3:067:0 0 6 = 08649204 2c01
f7106180 3224221480 S Ci:3:067:0 s a1 01 0309 0000 0002 8 <
f7106180 3224225466 C Ci:3:067:0 0 2 = 0978
f7106180 3224225473 S Ci:3:067:0 s a1 01 030a 0000 0002 8 <
f7106180 3224229467 C Ci:3:067:0 0 2 = 0a49
f7106180 3224229474 S Ci:3:067:0 s a1 01 030b 0000 0002 8 <
f7106180 3224233466 C Ci:3:067:0 0 2 = 0b11
f7106180 3224233482 S Ci:3:067:0 s a1 01 030c 0000 0002 8 <
f7106180 3224237467 C Ci:3:067:0 0 2 = 0c02
f7106180 3224237474 S Ci:3:067:0 s a1 01 030d 0000 0002 8 <
f7106180 3224241467 C Ci:3:067:0 0 2 = 0d03
f7106180 3224241474 S Ci:3:067:0 s a1 01 030e 0000 0003 8 <
f7106180 3224245467 C Ci:3:067:0 0 3 = 0e7800
f7106180 3224245474 S Ci:3:067:0 s a1 01 030f 0000 0003 8 <
f7106180 3224249466 C Ci:3:067:0 0 3 = 0f7500
f7106180 3224249474 S Ci:3:067:0 s a1 01 0310 0000 0005 8 <
f7106180 3224253466 C Ci:3:067:0 0 5 = 1060008c 00
f7106180 3224253475 S Ci:3:067:0 s a1 01 0312 0000 0003 8 <
f7106180 3224257466 C Ci:3:067:0 0 3 = 127500
f7106180 3224257473 S Ci:3:067:0 s a1 01 0313 0000 0002 8 <
f7106180 3224261467 C Ci:3:067:0 0 2 = 1318
f7106180 3224261473 S Ci:3:067:0 s a1 01 0314 0000 0002 8 <
f7106180 3224265466 C Ci:3:067:0 0 2 = 1400
f7106180 3224265473 S Ci:3:067:0 s a1 01 0315 0000 0003 8 <
f7106180 3224269465 C Ci:3:067:0 0 3 = 15ffff
f7106180 3224269472 S Ci:3:067:0 s a1 01 0316 0000 0003 8 <
f7106180 3224273466 C Ci:3:067:0 0 3 = 160000
f7106180 3224273473 S Ci:3:067:0 s a1 01 0318 0000 0003 8 <
f7106180 3224277465 C Ci:3:067:0 0 3 = 18ff00
f7106c80 3224277722 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
f7106c80 3224277727 C Ci:3:001:0 0 4 = 03030000
