usbdux registers, but communcation to device times out

[Melanie Dumas <melanie.dumas@gmail.com>]

[-- Attachment #1: Type: text/plain, Size: 1274 bytes --]

Hello,

I am working with Fedora Core 2 and the regular (not fast) usbdux
controller. We recently upgraded our Dell 2650 machine from RH 9.0
(kernel 2.4) to Fedora Core 2, kernel 2.6.10-1.771_FC2smp. The usb-dux
controller worked great on our old kernel, but the new kernel has the
following problem.

We installed comedi and comedilib according to the instructions on
http://www.linux-usb-daq.co.uk/drivers2/2.6/. There were no errors on
installation, and the usbdux drivers were auto-detected by hotplug
when the usbdux controller was plugged in. We saw the message "kernel:
comedi0: usbdux: usb-device 0 is attached to comedi."

However, when we run the demo programs or our old programs, we always
get errno 110. The accompanying error message is something like
"kernel: comedi0: could not transmit dux_command to the usb-device",
or "kernel: usb1-1: lt-info timed out on ep4out". dmesg, lsmod, and
/proc/comedi output files are attached. I'm very confused, since the
device registered just fine with the usb-hotplug, so it must be
talking. Can someone please explain this error and offer suggestions
to resolve it?

Linux linux1 2.6.10-1.771_FC2smp #1 SMP Mon Mar 28 01:10:51 EST 2005
i686 i686 i386 GNU/Linux
comedilib-0.7.22
comedi-0.7.69

Thanks in advance,
Melanie

[-- Attachment #2: dmesg.txt --]
[-- Type: text/plain, Size: 15765 bytes --]

led.
ACPI: PCI Interrupt Link [LNK2] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LNK3] (IRQs 3 4 5 6 7 9 10 *11 12 14)
ACPI: PCI Interrupt Link [LNK4] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LNK5] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LNK6] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LNK7] (IRQs 3 4 5 6 7 9 *10 11 12 14)
ACPI: PCI Interrupt Link [LNK8] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LNK9] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 *7 9 10 11 12 14)
ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 9 *10 11 12 14)
ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 *7 9 10 11 12 14)
ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 9 10 *11 12 14)
ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 9 *10 11 12 14)
ACPI: PCI Interrupt Link [LN10] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN11] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN12] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN13] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN14] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN15] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN16] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN17] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN18] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN19] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN1A] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN1B] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN1C] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN1D] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN1E] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LN1F] (IRQs 3 4 5 6 7 9 10 11 12 14) *0, disabled.
ACPI: PCI Interrupt Link [LUSB] (IRQs 3 4 *5 6 7 10 11 12 14)
Linux Plug and Play Support v0.97 (c) Adam Belay
pnp: PnP ACPI init
pnp: PnP ACPI: found 11 devices
usbcore: registered new driver usbfs
usbcore: registered new driver hub
PCI: Using ACPI for IRQ routing
** PCI interrupts are no longer routed automatically.  If this
** causes a device to stop working, it is probably because the
** driver failed to call pci_enable_device().  As a temporary
** workaround, the "pci=routeirq" argument restores the old
** behavior.  If this argument makes the device work again,
** please email the output of "lspci" to bjorn.helgaas@hp.com
** so I can fix the driver.
pnp: 00:09: ioport range 0x800-0x89f could not be reserved
pnp: 00:09: ioport range 0x8a0-0x8af has been reserved
pnp: 00:09: ioport range 0xc00-0xcd7 has been reserved
pnp: 00:09: ioport range 0xf50-0xf58 has been reserved
pnp: 00:09: ioport range 0x8e0-0x8e3 has been reserved
apm: BIOS not found.
audit: initializing netlink socket (disabled)
audit(1112905621.908:0): initialized
highmem bounce pool size: 64 pages
Total HugeTLB memory allocated, 0
VFS: Disk quotas dquot_6.5.1
Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
SELinux:  Registering netfilter hooks
Initializing Cryptographic API
ksign: Installing public key data
Loading keyring
- Added public key 4E4AD4CE5D64C848
- User ID: Red Hat, Inc. (Kernel Module GPG key)
pci_hotplug: PCI Hot Plug PCI Core version: 0.5
isapnp: Scanning for PnP cards...
isapnp: No Plug & Play device found
Real Time Clock Driver v1.12
Linux agpgart interface v0.100 (c) Dave Jones
serio: i8042 AUX port at 0x60,0x64 irq 12
serio: i8042 KBD port at 0x60,0x64 irq 1
Serial: 8250/16550 driver $Revision: 1.90 $ 76 ports, IRQ sharing enabled
ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
ACPI: PCI interrupt 0000:00:04.1[B] -> GSI 23 (level, low) -> IRQ 177
ttyS14 at I/O 0xec80 (irq = 177) is a 16550A
ACPI: PCI interrupt 0000:01:06.0[A] -> GSI 16 (level, low) -> IRQ 185
ttyS15 at I/O 0xdc70 (irq = 185) is a XR16850
ttyS44 at I/O 0xdc60 (irq = 185) is a XR16850
ttyS45 at I/O 0xdc50 (irq = 185) is a XR16850
ttyS46 at I/O 0xdc40 (irq = 185) is a XR16850
ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
io scheduler noop registered
io scheduler anticipatory registered
io scheduler deadline registered
io scheduler cfq registered
RAMDISK driver initialized: 16 RAM disks of 16384K size 1024 blocksize
divert: not allocating divert_blk for non-ethernet device lo
Uniform Multi-Platform E-IDE driver Revision: 7.00alpha2
ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
SvrWks CSB5: IDE controller at PCI slot 0000:00:0f.1
SvrWks CSB5: chipset revision 147
SvrWks CSB5: not 100% native mode: will probe irqs later
SvrWks CSB5: simplex device: DMA forced
    ide0: BM-DMA at 0x08b0-0x08b7, BIOS settings: hda:DMA, hdb:pio
SvrWks CSB5: simplex device: DMA forced
    ide1: BM-DMA at 0x08b8-0x08bf, BIOS settings: hdc:DMA, hdd:DMA
Probing IDE interface ide0...
hda: SAMSUNG CD-ROM SN-124, ATAPI CD/DVD-ROM drive
elevator: using anticipatory as default io scheduler
ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
Probing IDE interface ide1...
ide1: Wait for ready failed before probe !
Probing IDE interface ide1...
ide1: Wait for ready failed before probe !
hda: ATAPI 24X CD-ROM drive, 128kB Cache, UDMA(33)
Uniform CD-ROM driver Revision: 3.20
ide-floppy driver 0.99.newide
usbcore: registered new driver hiddev
usbcore: registered new driver usbhid
drivers/usb/input/hid-core.c: v2.0:USB HID core driver
mice: PS/2 mouse device common for all mice
input: AT Translated Set 2 keyboard on isa0060/serio0
input: ImExPS/2 Generic Explorer Mouse on isa0060/serio1
md: md driver 0.90.1 MAX_MD_DEVS=256, MD_SB_DISKS=27
NET: Registered protocol family 2
IP: routing cache hash table of 8192 buckets, 128Kbytes
TCP: Hash tables configured (established 262144 bind 43690)
Initializing IPsec netlink socket
NET: Registered protocol family 1
NET: Registered protocol family 17
ACPI wakeup devices: 
 RTC NIC1 NIC2 PCI2 PCI1 
ACPI: (supports S0 S4 S5)
CPU0:
 domain 0: span 00000003
  groups: 00000001 00000002
  domain 1: span 0000000f
   groups: 00000003 0000000c
CPU1:
 domain 0: span 00000003
  groups: 00000002 00000001
  domain 1: span 0000000f
   groups: 00000003 0000000c
CPU2:
 domain 0: span 0000000c
  groups: 00000004 00000008
  domain 1: span 0000000f
   groups: 0000000c 00000003
CPU3:
 domain 0: span 0000000c
  groups: 00000008 00000004
  domain 1: span 0000000f
   groups: 0000000c 00000003
md: Autodetecting RAID arrays.
md: autorun ...
md: ... autorun DONE.
RAMDISK: Compressed image found at block 0
VFS: Mounted root (ext2 filesystem).
SCSI subsystem initialized
Red Hat/Adaptec aacraid driver (1.1.2-lk2 Mar 28 2005)
ACPI: PCI interrupt 0000:04:08.1[A] -> GSI 30 (level, low) -> IRQ 193
AAC0: kernel 2.7.4 build 3170
AAC0: monitor 2.7.4 build 3170
AAC0: bios 2.7.0 build 3170
AAC0: serial f79041d3fafaf001
AAC0: 64bit support enabled.
AAC0: 64 Bit DAC enabled
scsi0 : percraid
  Vendor: DELL      Model: Linux 1           Rev: V1.0
  Type:   Direct-Access                      ANSI SCSI revision: 02
SCSI device sda: 142182912 512-byte hdwr sectors (72798 MB)
sda: Write Protect is off
sda: Mode Sense: 03 00 00 00
SCSI device sda: drive cache: write through
SCSI device sda: 142182912 512-byte hdwr sectors (72798 MB)
sda: Write Protect is off
sda: Mode Sense: 03 00 00 00
SCSI device sda: drive cache: write through
 sda: sda1 sda2 sda3
Attached scsi removable disk sda at scsi0, channel 0, id 0, lun 0
kjournald starting.  Commit interval 5 seconds
EXT3-fs: mounted filesystem with ordered data mode.
Freeing unused kernel memory: 208k freed
SELinux:  Disabled at runtime.
SELinux:  Unregistering netfilter hooks
NET: Registered protocol family 10
Disabled Privacy Extensions on device c0330380(lo)
IPv6 over IPv4 tunneling driver
divert: not allocating divert_blk for non-ethernet device sit0
mtrr: type mismatch for fd000000,800000 old: uncachable new: write-combining
mtrr: type mismatch for fd000000,800000 old: uncachable new: write-combining
ACPI: Power Button (FF) [PWRF]
ibm_acpi: ec object not found
ohci_hcd: 2004 Nov 08 USB 1.1 'Open' Host Controller (OHCI) Driver (PCI)
ACPI: PCI Interrupt Link [LUSB] enabled at IRQ 11
ACPI: PCI interrupt 0000:00:0f.2[A] -> GSI 11 (level, low) -> IRQ 11
ohci_hcd 0000:00:0f.2: OHCI Host Controller
ohci_hcd 0000:00:0f.2: irq 11, pci mem 0xfe100000
ohci_hcd 0000:00:0f.2: new USB bus registered, assigned bus number 1
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 4 ports detected
usb 1-1: new full speed USB device using ohci_hcd and address 2
EXT3 FS on sda2, internal journal
device-mapper: 4.3.0-ioctl (2004-09-30) initialised: dm-devel@redhat.com
Adding 2040244k swap on /dev/sda3.  Priority:-1 extents:1
program scsi_unique_id is using a deprecated SCSI ioctl, please convert it to SG_IO
program scsi_unique_id is using a deprecated SCSI ioctl, please convert it to SG_IO
program scsi_unique_id is using a deprecated SCSI ioctl, please convert it to SG_IO
program scsi_unique_id is using a deprecated SCSI ioctl, please convert it to SG_IO
program scsi_unique_id is using a deprecated SCSI ioctl, please convert it to SG_IO
program scsi_unique_id is using a deprecated SCSI ioctl, please convert it to SG_IO
program scsi_unique_id is using a deprecated SCSI ioctl, please convert it to SG_IO
program scsi_unique_id is using a deprecated SCSI ioctl, please convert it to SG_IO
kjournald starting.  Commit interval 5 seconds
EXT3 FS on sda1, internal journal
EXT3-fs: mounted filesystem with ordered data mode.
IA-32 Microcode Update Driver: v1.14 <tigran@veritas.com>
microcode: CPU3 updated from revision 0x25 to 0x38, date = 06042003 
microcode: CPU1 updated from revision 0x25 to 0x38, date = 06042003 
microcode: CPU2 updated from revision 0x25 to 0x38, date = 06042003 
microcode: CPU0 updated from revision 0x25 to 0x38, date = 06042003 
Attached scsi generic sg0 at scsi0, channel 0, id 0, lun 0,  type 0
Floppy drive(s): fd0 is 1.44M
FDC 0 is a National Semiconductor PC87306
ieee1394: Initialized config rom entry `ip1394'
tg3.c:v3.14 (November 15, 2004)
ACPI: PCI interrupt 0000:03:06.0[A] -> GSI 28 (level, low) -> IRQ 201
divert: allocating divert_blk for eth0
eth0: Tigon3 [partno(BCM95701A10) rev 0105 PHY(5701)] (PCIX:133MHz:64-bit) 10/100/1000BaseT Ethernet 00:06:5b:8e:15:df
eth0: RXcsums[1] LinkChgREG[1] MIirq[1] ASF[0] Split[0] WireSpeed[1] TSOcap[0] 
ACPI: PCI interrupt 0000:03:08.0[A] -> GSI 29 (level, low) -> IRQ 209
divert: allocating divert_blk for eth1
eth1: Tigon3 [partno(BCM95701A10) rev 0105 PHY(5701)] (PCIX:133MHz:64-bit) 10/100/1000BaseT Ethernet 00:06:5b:8e:15:e0
eth1: RXcsums[1] LinkChgREG[1] MIirq[1] ASF[0] Split[0] WireSpeed[1] TSOcap[0] 
divert: freeing divert_blk for eth0
divert: freeing divert_blk for eth1
ip_tables: (C) 2000-2002 Netfilter core team
tg3.c:v3.14 (November 15, 2004)
ACPI: PCI interrupt 0000:03:06.0[A] -> GSI 28 (level, low) -> IRQ 201
divert: allocating divert_blk for eth0
eth0: Tigon3 [partno(BCM95701A10) rev 0105 PHY(5701)] (PCIX:133MHz:64-bit) 10/100/1000BaseT Ethernet 00:06:5b:8e:15:df
eth0: RXcsums[1] LinkChgREG[1] MIirq[1] ASF[0] Split[0] WireSpeed[1] TSOcap[0] 
ACPI: PCI interrupt 0000:03:08.0[A] -> GSI 29 (level, low) -> IRQ 209
divert: allocating divert_blk for eth1
eth1: Tigon3 [partno(BCM95701A10) rev 0105 PHY(5701)] (PCIX:133MHz:64-bit) 10/100/1000BaseT Ethernet 00:06:5b:8e:15:e0
eth1: RXcsums[1] LinkChgREG[1] MIirq[1] ASF[0] Split[0] WireSpeed[1] TSOcap[0] 
ip_tables: (C) 2000-2002 Netfilter core team
tg3: eth0: Link is up at 1000 Mbps, full duplex.
tg3: eth0: Flow control is on for TX and on for RX.
mtrr: type mismatch for fd000000,800000 old: uncachable new: write-combining
mtrr: type mismatch for fd000000,800000 old: uncachable new: write-combining
eth0: no IPv6 routers present
comedi: no version for "struct_module" found: kernel tainted.
comedi: version 0.7.69 - David Schleef <ds@schleef.org>
usbdux.c: v1.00pre10:Stirling/ITL USB-DUX -- Bernd.Porr@f2s.com
comedi_: usbdux: setting all possible devs to invalid
comedi_: usbdux_: finding a free structure for the usb-device
comedi_: usbdux: usbduxsub[0] is ready to connect to comedi.
comedi_: usbdux: ifnum=0
comedi_: usbdux0 has been successfully initialized.
usbcore: registered new driver usbdux
usb 1-1: USB disconnect, address 2
comedi_: usbdux: tiding up
comedi_: usbdux: disconnected from the usb
usb 1-1: new full speed USB device using ohci_hcd and address 3
comedi_: usbdux_: finding a free structure for the usb-device
comedi_: usbdux: usbduxsub[0] is ready to connect to comedi.
comedi_: usbdux: ifnum=0
comedi_: usbdux0 has been successfully initialized.
comedi0: usbdux: uploading 1471 bytes to addr 0, first byte=2.
usb 1-1: comedi_config timed out on ep0out
comedi_: usbdux: result=-110
comedi_: usbdux: uppload failed
comedi_: usbdux: firmware upload failed
comedi0: usbdux: usb-device 0 is attached to comedi.
comedi0: successfully attached to usbdux.
usb 1-1: lt-cmd timed out on ep4out
comedi0: could not transmit dux_command to the usb-device, err=-110
comedi0: usbdux_ai_cmdtest
comedi0: usbdux_ai_cmdtest
comedi0: usbdux_ai_cmdtest
comedi0: usbdux_ai_cmdtest
comedi0: usbdux_ai_cmdtest
comedi0: usbdux_ai_cmd
comedi 0: sending commands to the usb device: size=8
usb 1-1: lt-cmd timed out on ep4out
comedi0: could not transmit dux_command to the usb-device, err=-110
usb 1-1: lt-insn timed out on ep4out
comedi0: could not transmit dux_command to the usb-device, err=-110
usb 1-1: lt-insn timed out on ep4out
comedi0: could not transmit dux_command to the usb-device, err=-110
usb 1-1: lt-insn timed out on ep4out
comedi0: could not transmit dux_command to the usb-device, err=-110
usb 1-1: lt-insn timed out on ep4out
comedi0: could not transmit dux_command to the usb-device, err=-110
usb 1-1: USB disconnect, address 3
comedi_: usbdux: tiding up
comedi_: usbdux: disconnected from the usb
usb 1-1: new full speed USB device using ohci_hcd and address 4
comedi_: usbdux_: finding a free structure for the usb-device
comedi_: usbdux: usbduxsub[0] is ready to connect to comedi.
comedi_: usbdux: ifnum=0
comedi_: usbdux0 has been successfully initialized.
comedi0: usbdux: detach usb device
comedi0: usbdux: detach: successfully removed
comedi0: usbdux: uploading 1471 bytes to addr 0, first byte=2.
usb 1-1: comedi_config timed out on ep0out
comedi_: usbdux: result=-110
comedi_: usbdux: uppload failed
comedi_: usbdux: firmware upload failed
comedi0: usbdux: usb-device 0 is attached to comedi.
comedi0: successfully attached to usbdux.
usb 1-1: lt-info timed out on ep4out
comedi0: could not transmit dux_command to the usb-device, err=-110
comedi0: usbdux_ai_cmdtest
comedi0: usbdux_ai_cmdtest
comedi0: usbdux_ai_cmdtest
comedi0: usbdux_ao_cmdtest
comedi0: usbdux_ao_cmdtest
comedi0: err=1, scan_begin_src=16, scan_begin_arg=1000000, convert_src=2, convert_arg=0
comedi0: usbdux_ao_cmdtest
comedi0: err=0, scan_begin_src=16, scan_begin_arg=1000000, convert_src=2, convert_arg=0
usb 1-1: lt-comedi_calib timed out on ep4out
comedi0: could not transmit dux_command to the usb-device, err=-110

[-- Attachment #3: lsmod.txt --]
[-- Type: text/plain, Size: 899 bytes --]

Module                  Size  Used by
usbdux                 26944  0 
comedi                 30736  1 usbdux
snd_mixer_oss          21185  0 
snd                    55461  1 snd_mixer_oss
soundcore              12961  1 snd
autofs4                21829  0 
sunrpc                135973  1 
tg3                    79557  0 
floppy                 56913  0 
sg                     38881  0 
microcode              11489  0 
dm_mod                 58181  0 
ohci_hcd               24277  0 
video                  19653  0 
button                 10577  0 
battery                13253  0 
ac                      8773  0 
md5                     8001  1 
ipv6                  240225  24 
ext3                  121929  2 
jbd                    57561  1 ext3
aacraid                44241  3 
sd_mod                 19905  4 
scsi_mod              116289  3 sg,aacraid,sd_mod

[-- Attachment #4: proc_comedi.txt --]
[-- Type: text/plain, Size: 170 bytes --]

comedi version 0.7.69
format string: "%2d: %-20s %-20s %4d",i,driver_name,board_name,n_subdevices
 0: usbdux               usbdux                  4
usbdux:
 usbdux