Hello,
I've been pawing through trouble-shooting notes trying to get control of a USB device under my own code.
I have the same code running on two computers; it works fine for one and not the other. My interface is written in Python, and I use pyusb 0.4.1, libusb-0.1, and up-to-date openSuSe 11.1 on both machines. I'm fairly sure the relevant parts of the /etc/udev/rules.d and /etc/fstab files are the same, though I haven't checked every one of the rules.d files.
I'm attaching scope-boot sequence output from usbmon for the two machines. The working one is daq and the non-working one is Johnny5... (There's a wry joke there for Short Circuit fans.) I can slowly process usbmon output manually, but I don't know what I'm looking for, or what other setup files might be implicated.
The usbmon output for the working machine is much longer during the boot sequence, and includes a comparable amount of data when I try to talk to it.
Here's an annotated version of the usbfs_snoop output. Again, I'm not real sure what I'm looking for in it:
24521a24522,26572
> Aug 18 16:51:05 Johnny5 kernel: usb 3-1: usbdev_ioctl: CONNECTINFO
> Aug 18 16:51:05 Johnny5 kernel: usb 3-1: usbdev_ioctl: IOCTL
> Aug 18 16:51:05 Johnny5 kernel: usb 1-2: usbdev_ioctl: CONNECTINFO
> Aug 18 16:51:05 Johnny5 kernel: usb 1-2: usbdev_ioctl: IOCTL
> Aug 18 16:51:05 Johnny5 kernel: usb 3-1: usbdev_ioctl: CONTROL
> Aug 18 16:51:05 Johnny5 kernel: usb 3-1: control read: bRequest=06 bRrequestType=80 wValue=0300 wIndex=0000 wLength=00ff
> Aug 18 16:51:05 Johnny5 kernel: usb 3-1: control read: data 04 03 09 04
> Aug 18 16:51:05 Johnny5 kernel: usb 3-1: usbdev_ioctl: CONTROL
> Aug 18 16:51:05 Johnny5 kernel: usb 3-1: control read: bRequest=06 bRrequestType=80 wValue=0303 wIndex=0409 wLength=00ff
> Aug 18 16:51:05 Johnny5 kernel: usb 3-1: control read: data 10 03 43 00 30 00 33 00 38 00 36 00 36 00 34 00
> Aug 18 16:51:10 Johnny5 kernel: usb 3-1: usbdev_ioctl: CONNECTINFO
> Aug 18 16:51:10 Johnny5 kernel: usb 3-1: usbdev_ioctl: IOCTL
> Aug 18 16:51:11 Johnny5 kernel: usb 1-2: usbdev_ioctl: CONNECTINFO
> Aug 18 16:51:11 Johnny5 kernel: usb 1-2: usbdev_ioctl: IOCTL
> Aug 18 16:51:11 Johnny5 kernel: usb 3-1: usbdev_ioctl: CONTROL
> Aug 18 16:51:11 Johnny5 kernel: usb 3-1: control read: bRequest=06 bRrequestType=80 wValue=0300 wIndex=0000 wLength=00ff
> Aug 18 16:51:11 Johnny5 kernel: usb 3-1: control read: data 04 03 09 04
> Aug 18 16:51:11 Johnny5 kernel: usb 3-1: usbdev_ioctl: CONTROL
> Aug 18 16:51:11 Johnny5 kernel: usb 3-1: control read: bRequest=06 bRrequestType=80 wValue=0303 wIndex=0409 wLength=00ff
> Aug 18 16:51:11 Johnny5 kernel: usb 3-1: control read: data 10 03 43 00 30 00 33 00 38 00 36 00 36 00 34 00
> Aug 18 16:51:11 Johnny5 kernel: usb 3-1: usbdev_ioctl: RESET
> Aug 18 16:51:11 Johnny5 kernel: usb 3-1: reset full speed USB device using uhci_hcd and address 3
> Aug 18 16:51:12 Johnny5 kernel: usb 3-1: usbdev_ioctl: CLAIMINTERFACE
> Aug 18 16:51:13 Johnny5 kernel: usb 3-1: usbdev_ioctl: SUBMITURB
> Aug 18 16:51:13 Johnny5 kernel: usb 3-1: bulk urb sending a bulk-out message
> Aug 18 16:51:13 Johnny5 kernel: usb 3-1: direction=OUT
> Aug 18 16:51:13 Johnny5 kernel: usb 3-1: userurb=00007f4b6319dfd0
> Aug 18 16:51:13 Johnny5 kernel: usb 3-1: transfer_buffer_length=20
> Aug 18 16:51:13 Johnny5 kernel: usb 3-1: actual_length=0
> Aug 18 16:51:13 Johnny5 kernel: usb 3-1: data: 01 01 fe 00 07 00 00 00 01 00 00 00 6c 6f 63 20 61 6c 6c 00 message 1, 7 characters: loc all
> Aug 18 16:51:13 Johnny5 kernel: usb 3-1: usbdev_ioctl: REAPURBDELAY
> Aug 18 16:51:13 Johnny5 kernel: usb 3-1: usbdev_ioctl: REAPURBDELAY
> Aug 18 16:51:13 Johnny5 kernel: usb 3-1: usbdev_ioctl: REAPURBDELAY
[...repeats many times over the course of the four-second timeout...]
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: usbdev_ioctl: REAPURBDELAY
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: usbdev_ioctl: REAPURBDELAY
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: usbdev_ioctl: REAPURBDELAY
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: usbdev_ioctl: SUBMITURB
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: bulk urb sending a bulk-out message
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: direction=OUT
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: userurb=00007f4b6319e700
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: transfer_buffer_length=20
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: actual_length=0
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: data: 01 01 fe 00 07 00 00 00 01 00 00 00 6c 6f 63 20 6e 6f 6e 00 message 1, 7 characters: loc non
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: usbdev_ioctl: REAPURBDELAY
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: usbdev_ioctl: REAPURBDELAY
> Aug 18 16:51:17 Johnny5 kernel: usb 3-1: usbdev_ioctl: REAPURBDELAY
[...repeats many times over the course of the four-second timeout...]
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: usbdev_ioctl: REAPURBDELAY
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: usbdev_ioctl: REAPURBDELAY
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: usbdev_ioctl: REAPURBDELAY
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: usbdev_ioctl: RELEASEINTERFACE
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: urb complete
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: direction=OUT
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: userurb=00007f4b6319dfd0
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: transfer_buffer_length=20
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: actual_length=0
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: data: 01 01 fe 00 07 00 00 00 01 00 00 00 6c 6f 63 20 61 6c 6c 00 message 1, 7 characters: loc all
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: urb complete
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: direction=OUT
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: userurb=00007f4b6319e700
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: transfer_buffer_length=20
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: actual_length=0
> Aug 18 16:51:21 Johnny5 kernel: usb 3-1: data: 01 01 fe 00 07 00 00 00 01 00 00 00 6c 6f 63 20 6e 6f 6e 00 message 1, 7 characters: loc non
I'd appreciate any suggestions you have about tracking down the difference / problem.
Thank you
-Sarah
ffff88007d99fd80 2897642659 C Ii:1:007:1 0:2048 1 = 02
ffff88007d99fd80 2897642675 S Ii:1:007:1 -115:2048 1 <
ffff88007e433cc0 2897642688 S Ci:1:007:0 s a3 00 0000 0001 0004 4 <
ffff88007e433cc0 2897642774 C Ci:1:007:0 0 4 = 00010100
ffff88007e433cc0 2897642784 S Co:1:007:0 s 23 01 0010 0001 0000 0
ffff88007e433cc0 2897642898 C Co:1:007:0 0 0
ffff88007d9a9980 2897643364 S Ci:1:007:0 s a3 00 0000 0001 0004 4 <
ffff88007d9a9980 2897643399 C Ci:1:007:0 0 4 = 00010000
ffff88007e4334c0 2897679556 S Ci:1:007:0 s a3 00 0000 0001 0004 4 <
ffff88007e4334c0 2897679651 C Ci:1:007:0 0 4 = 00010000
ffff88007e4334c0 2897707530 S Ci:1:007:0 s a3 00 0000 0001 0004 4 <
ffff88007e4334c0 2897707648 C Ci:1:007:0 0 4 = 00010000
ffff88007e4334c0 2897739541 S Ci:1:007:0 s a3 00 0000 0001 0004 4 <
ffff88007e4334c0 2897739648 C Ci:1:007:0 0 4 = 00010000
ffff88007e4334c0 2897771529 S Ci:1:007:0 s a3 00 0000 0001 0004 4 <
ffff88007e4334c0 2897771648 C Ci:1:007:0 0 4 = 00010000
ffff88007d99fd80 2899979679 C Ii:1:007:1 -2:2048 0
ffff88007e4334c0 2899979690 S Co:1:007:0 s 00 03 0001 0000 0000 0
ffff88007e4334c0 2899979778 C Co:1:007:0 0 0
ffff88007e4334c0 2899979821 S Co:1:002:0 s 23 03 0002 0004 0000 0
ffff88007e4334c0 2899979901 C Co:1:002:0 0 0
ffff88007d4ff6c0 2929386709 C Ii:1:002:1 0:2048 1 = 10
ffff88007d4ff6c0 2929386723 S Ii:1:002:1 -115:2048 1 <
ffff880074046a80 2929386792 S Ci:1:002:0 s a3 00 0000 0004 0004 4 <
ffff880074046a80 2929386822 C Ci:1:002:0 0 4 = 03050400
ffff880074046a80 2929386848 S Co:1:002:0 s 23 01 0012 0004 0000 0
ffff880074046a80 2929386945 C Co:1:002:0 0 0
ffff880074046a80 2929399531 S Ci:1:002:0 s a3 00 0000 0004 0004 4 <
ffff880074046a80 2929399570 C Ci:1:002:0 0 4 = 03050000
ffff880074046a80 2929399598 S Ci:1:007:0 s 80 00 0000 0000 0002 2 <
ffff880074046a80 2929399696 C Ci:1:007:0 0 2 = 0300
ffff880074046a80 2929399721 S Co:1:007:0 s 00 01 0001 0000 0000 0
ffff880074046a80 2929399821 C Co:1:007:0 0 0
ffff880074046a80 2929399846 S Ci:1:007:0 s a3 00 0000 0001 0004 4 <
ffff880074046a80 2929399946 C Ci:1:007:0 0 4 = 01010100
ffff880074046a80 2929399971 S Co:1:007:0 s 23 01 0010 0001 0000 0
ffff880074046a80 2929400070 C Co:1:007:0 0 0
ffff880074046a80 2929400095 S Ci:1:007:0 s a3 00 0000 0002 0004 4 <
ffff880074046a80 2929400195 C Ci:1:007:0 0 4 = 00010000
ffff880074046a80 2929400220 S Ci:1:007:0 s a3 00 0000 0003 0004 4 <
ffff880074046a80 2929400320 C Ci:1:007:0 0 4 = 00010000
ffff880074046a80 2929400345 S Ci:1:007:0 s a3 00 0000 0004 0004 4 <
ffff880074046a80 2929400446 C Ci:1:007:0 0 4 = 00010000
ffff880074046a80 2929400471 S Ci:1:007:0 s a3 00 0000 0005 0004 4 <
ffff880074046a80 2929400571 C Ci:1:007:0 0 4 = 00010000
ffff880074046a80 2929400596 S Ci:1:007:0 s a3 00 0000 0006 0004 4 <
ffff880074046a80 2929400696 C Ci:1:007:0 0 4 = 00010000
ffff880074046a80 2929400720 S Ci:1:007:0 s a3 00 0000 0007 0004 4 <
ffff880074046a80 2929400820 C Ci:1:007:0 0 4 = 00010000
ffff88007d99fd80 2929503528 S Ii:1:007:1 -115:2048 1 <
ffff880074046a80 2929503537 S Ci:1:007:0 s a3 00 0000 0001 0004 4 <
ffff880074046a80 2929503570 C Ci:1:007:0 0 4 = 01010000
ffff880074046a80 2929503606 S Co:1:007:0 s 23 03 0004 0001 0000 0
ffff880074046a80 2929503695 C Co:1:007:0 0 0
ffff880074046a80 2929519529 S Ci:1:007:0 s a3 00 0000 0001 0004 4 <
ffff880074046a80 2929519571 C Ci:1:007:0 0 4 = 03011000
ffff880074046a80 2929575530 S Co:1:007:0 s 23 01 0014 0001 0000 0
ffff880074046a80 2929575571 C Co:1:007:0 0 0
ffff880074046a80 2929575613 S Ci:1:000:0 s 80 06 0100 0000 0040 64 <
ffff880074046a80 2929575821 C Ci:1:000:0 0 18 = 12010002 00000040 99066803 42000102 0301
ffff880074046a80 2929575847 S Co:1:007:0 s 23 03 0004 0001 0000 0
ffff880074046a80 2929575946 C Co:1:007:0 0 0
ffff880074046a80 2929591530 S Ci:1:007:0 s a3 00 0000 0001 0004 4 <
ffff880074046a80 2929591571 C Ci:1:007:0 0 4 = 03011000
ffff88007d99fd80 2929642708 C Ii:1:007:1 0:2048 1 = 02
ffff88007d99fd80 2929642712 S Ii:1:007:1 -115:2048 1 <
ffff880074046a80 2929647530 S Co:1:007:0 s 23 01 0014 0001 0000 0
ffff880074046a80 2929647571 C Co:1:007:0 0 0
ffff880074046a80 2929647598 S Co:1:000:0 s 00 05 0015 0000 0000 0
ffff880074046a80 2929647821 C Co:1:000:0 0 0
ffff880074046a80 2929667530 S Ci:1:021:0 s 80 06 0100 0000 0012 18 <
ffff880074046a80 2929667696 C Ci:1:021:0 0 18 = 12010002 00000040 99066803 42000102 0301
ffff880074046a80 2929667726 S Ci:1:021:0 s 80 06 0600 0000 000a 10 <
ffff880074046a80 2929667821 C Ci:1:021:0 -32 0
ffff880074046a80 2929667848 S Ci:1:021:0 s 80 06 0600 0000 000a 10 <
ffff880074046a80 2929668070 C Ci:1:021:0 -32 0
ffff880074046a80 2929668097 S Ci:1:021:0 s 80 06 0600 0000 000a 10 <
ffff880074046a80 2929668321 C Ci:1:021:0 -32 0
ffff880074046a80 2929668351 S Ci:1:021:0 s 80 06 0200 0000 0009 9 <
ffff880074046a80 2929668571 C Ci:1:021:0 0 9 = 09022700 010100c0 32
ffff880074046a80 2929668598 S Ci:1:021:0 s 80 06 0200 0000 0027 39 <
ffff880074046a80 2929668821 C Ci:1:021:0 0 39 = 09022700 010100c0 32090400 0003fe03 01000705 85024000 00070506 02400000
ffff880074046280 2929668853 S Ci:1:021:0 s 80 06 0300 0000 00ff 255 <
ffff880074046280 2929668946 C Ci:1:021:0 0 4 = 04030904
ffff880074046280 2929668971 S Ci:1:021:0 s 80 06 0302 0409 00ff 255 <
ffff880074046280 2929669071 C Ci:1:021:0 0 38 = 26035400 65006b00 74007200 6f006e00 69007800 20005400 44005300 32003000
ffff880074046280 2929669100 S Ci:1:021:0 s 80 06 0301 0409 00ff 255 <
ffff880074046280 2929669196 C Ci:1:021:0 0 32 = 20035400 65006b00 74007200 6f006e00 69007800 2c002000 49006e00 63002e00
ffff880074046280 2929669224 S Ci:1:021:0 s 80 06 0303 0409 00ff 255 <
ffff880074046280 2929669321 C Ci:1:021:0 0 16 = 10034300 30003300 38003800 36003300
ffff880074046b80 2929669488 S Co:1:021:0 s 00 09 0001 0000 0000 0
ffff880074046b80 2929669696 C Co:1:021:0 0 0
ffff880074046b80 2929670002 S Ci:1:007:0 s a3 00 0000 0001 0004 4 <
ffff880074046b80 2929670071 C Ci:1:007:0 0 4 = 03010000
ffff88007dd1c4c0 3189519458 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
ffff88007dd1c4c0 3189519475 C Ci:3:001:0 0 4 = 01010100
ffff88007dd1c4c0 3189519481 S Co:3:001:0 s 23 01 0010 0001 0000 0
ffff88007dd1c4c0 3189519484 C Co:3:001:0 0 0
ffff88007dd1c4c0 3189519486 S Ci:3:001:0 s a3 00 0000 0002 0004 4 <
ffff88007dd1c4c0 3189519490 C Ci:3:001:0 0 4 = 00010000
ffff88007d47ec80 3189623546 S Ii:3:001:1 -115:128 2 <
ffff88007dd1c4c0 3189623558 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
ffff88007dd1c4c0 3189623562 C Ci:3:001:0 0 4 = 01010000
ffff88007dd1c4c0 3189623571 S Co:3:001:0 s 23 03 0004 0001 0000 0
ffff88007dd1c4c0 3189623575 C Co:3:001:0 0 0
ffff88007dd1c4c0 3189679732 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
ffff88007dd1c4c0 3189679755 C Ci:3:001:0 0 4 = 03010000
ffff88007dd1c4c0 3189735931 S Co:3:001:0 s 23 01 0014 0001 0000 0
ffff88007dd1c4c0 3189735935 C Co:3:001:0 0 0
ffff88007dd1c4c0 3189735944 S Ci:3:000:0 s 80 06 0100 0000 0040 64 <
ffff88007dd1c4c0 3189739939 C Ci:3:000:0 0 18 = 12010002 00000040 99066803 42000102 0301
ffff88007dd1c4c0 3189740237 S Co:3:001:0 s 23 03 0004 0001 0000 0
ffff88007dd1c4c0 3189740245 C Co:3:001:0 0 0
ffff88007dd1c4c0 3189795942 S Ci:3:001:0 s a3 00 0000 0001 0004 4 <
ffff88007dd1c4c0 3189795965 C Ci:3:001:0 0 4 = 03010000
ffff88007dd1c4c0 3189851930 S Co:3:001:0 s 23 01 0014 0001 0000 0
ffff88007dd1c4c0 3189851935 C Co:3:001:0 0 0
ffff88007dd1c4c0 3189851943 S Co:3:000:0 s 00 05 000a 0000 0000 0
ffff88007dd1c4c0 3189854931 C Co:3:000:0 0 0
ffff88007dd1c4c0 3189871932 S Ci:3:010:0 s 80 06 0100 0000 0012 18 <
ffff88007dd1c4c0 3189875930 C Ci:3:010:0 0 18 = 12010002 00000040 99066803 42000102 0301
ffff88007dd1c4c0 3189875956 S Ci:3:010:0 s 80 06 0600 0000 000a 10 <
ffff88007dd1c4c0 3189878929 C Ci:3:010:0 -32 0
ffff88007dd1c4c0 3189878952 S Ci:3:010:0 s 80 06 0600 0000 000a 10 <
ffff88007dd1c4c0 3189881929 C Ci:3:010:0 -32 0
ffff88007dd1c4c0 3189881952 S Ci:3:010:0 s 80 06 0600 0000 000a 10 <
ffff88007dd1c4c0 3189884929 C Ci:3:010:0 -32 0
ffff88007dd1c4c0 3189884954 S Ci:3:010:0 s 80 06 0200 0000 0009 9 <
ffff88007dd1c4c0 3189888929 C Ci:3:010:0 0 9 = 09022700 010100c0 32
ffff88007dd1c4c0 3189888953 S Ci:3:010:0 s 80 06 0200 0000 0027 39 <
ffff88007dd1c4c0 3189892929 C Ci:3:010:0 0 39 = 09022700 010100c0 32090400 0003fe03 01000705 85024000 00070506 02400000
ffff88007e955980 3189892974 S Ci:3:010:0 s 80 06 0300 0000 00ff 255 <
ffff88007e955980 3189897929 C Ci:3:010:0 0 4 = 04030904
ffff88007e955980 3189897953 S Ci:3:010:0 s 80 06 0302 0409 00ff 255 <
ffff88007e955980 3189902928 C Ci:3:010:0 0 38 = 26035400 65006b00 74007200 6f006e00 69007800 20005400 44005300 32003000
ffff88007e955980 3189902956 S Ci:3:010:0 s 80 06 0301 0409 00ff 255 <
ffff88007e955980 3189907928 C Ci:3:010:0 0 32 = 20035400 65006b00 74007200 6f006e00 69007800 2c002000 49006e00 63002e00
ffff88007e955980 3189907957 S Ci:3:010:0 s 80 06 0303 0409 00ff 255 <
ffff88007e955980 3189912927 C Ci:3:010:0 0 16 = 10034300 30003300 38003600 36003400
ffff88007dd1c0c0 3189913064 S Co:3:010:0 s 00 09 0001 0000 0000 0
ffff88007dd1c0c0 3189915935 C Co:3:010:0 0 0
