5579 – Microtek driver: error -22 submitting URB, kernel-2.6.14.

Bug 5579 - Microtek driver: error -22 submitting URB, kernel-2.6.14.

Summary: Microtek driver: error -22 submitting URB, kernel-2.6.14.

Status:	RESOLVED CODE_FIX

Alias:	None

Product:	Drivers
Classification:	Unclassified
Component:	USB (show other bugs)
Hardware:	i386 Linux

Importance:	P2 normal
Assignee:	Greg Kroah-Hartman

URL:
Keywords:

Depends on:
Blocks:	USB
	Show dependency tree

Reported:	2005-11-09 16:25 UTC by David
Modified:	2005-11-24 19:46 UTC (History)
CC List:	1 user (show)

See Also:
Kernel Version:	2.6.14
Subsystem:
Regression:	---
Bisected commit-id:

Attachments
2.6.14 kernel system Dmesg output. (15.20 KB, text/plain) 2005-11-09 16:29 UTC, David	Details
2.6.14 kernel system /var/log/debug output after plugging scanner in. (1.96 KB, text/plain) 2005-11-09 16:31 UTC, David	Details
2.6.14 kernel system /var/log/hotplug/evens output after plugging scanner in. (663 bytes, text/plain) 2005-11-09 16:31 UTC, David	Details
2.6.14 kernel system /var/log/kern.log output after plugging scanner in. (2.51 KB, text/plain) 2005-11-09 16:32 UTC, David	Details
2.6.13 kernel system log outputs from the Same system that work. (6.14 KB, text/plain) 2005-11-09 16:34 UTC, David	Details
2.6.14 kernel system hardware info. (26.82 KB, text/plain) 2005-11-09 16:41 UTC, David	Details
2.6.14 kernel system software info. (1.25 KB, text/plain) 2005-11-09 16:42 UTC, David	Details
2.6.14 kernel system with more debug enabled in drivers. (94.03 KB, text/plain) 2005-11-11 14:09 UTC, David	Details
Patch for Microtek driver: error -22 submitting URB (2.85 KB, patch) 2005-11-15 04:27 UTC, David	Details \| Diff
Add an attachment (proposed patch, testcase, etc.)

Description David 2005-11-09 16:25:49 UTC

Most recent kernel where this bug did not occur:2.6.13
Distribution: Gentoo AMD64
Hardware Environment: Gigabyte GA-K8NS Ultra-939, Nvidia GeForce 6800, attached
files containing /proc/ioports, /proc/iomem, lsusb, lspci
Software Environment: attached output of ver_linux script
Problem Description:
Microtek Scanmaker V6UPL error: error -22 submitting URB.
Have several kernels on same system all work except for kernel-2.6.14 and 2.6.14_rc5
When plug in scanner or during boot, major difference between working and 
non-working kernel is shown below.

From kern.log on 2.6.14 kernel:
....
Nov  8 07:34:34 decibels scsi1 : microtekX6
Nov  8 07:34:34 decibels ohci_hcd 0000:00:02.0: urb ffff81002acce780 path 2
ep2in 83160000 cc 8 --> status -75
Nov  8 07:34:34 decibels microtek usb (rev 0.4.3): error -22 submitting URB
Nov  8 07:34:34 decibels microtek usb (rev 0.4.3): error -22 submitting URB
Nov  8 07:34:34 decibels hub 2-0:1.0: state 5 ports 4 chg 0000 evt 0004

Noticed first in 2.6.14_rc5, tried 2.6.14 and same results. 
Kernel-2.6.11 and 2.6.13 and both work, so have previous kernels.

This scanner is a usb scanner that uses the scsi generic driver. 
From kern.log on working kernel.
 
Nov  5 15:49:06 decibels scsi2 : microtekX6
Nov  8 15:49:06 decibels Vendor:           Model: Scanner V6UPL     Rev: 1.00
Nov  8 15:49:06 decibels Type:   Scanner                            ANSI SCSI
revision: 02
Nov  8 15:49:06 decibels Attached scsi generic sg0 at scsi2, channel 0, id 0,
lun 0,  type 6
Nov  8 15:49:06 decibels hub 2-0:1.0: state 5 ports 4 chg 0000 evt 0004

Yes, this scanner is still supported and is listed in the kernel microtek.c source. 

Steps to reproduce: reboot or unplug/replug in scanner.

Comment 1 David 2005-11-09 16:29:35 UTC

Created attachment 6516 [details]
2.6.14 kernel system Dmesg output.

Comment 2 David 2005-11-09 16:31:05 UTC

Created attachment 6517 [details]
2.6.14 kernel system /var/log/debug output after plugging scanner in.

Comment 3 David 2005-11-09 16:31:56 UTC

Created attachment 6518 [details]
2.6.14 kernel system /var/log/hotplug/evens output after plugging scanner in.

Comment 4 David 2005-11-09 16:32:44 UTC

Created attachment 6519 [details]
2.6.14 kernel system /var/log/kern.log output after plugging scanner in.

Comment 5 David 2005-11-09 16:34:48 UTC

Created attachment 6520 [details]
2.6.13 kernel system log outputs from the Same system that work.

Comment 6 David 2005-11-09 16:41:18 UTC

Created attachment 6521 [details]
2.6.14 kernel system hardware info.

This file contains: lspci -vvv, lsusb -v, /proc/ioports, /proc/iomem,
/proc/modules.

Note: this is same computer used with the 2.6.14 kernel having problems with.
Only thing basically different is the kernel.

Comment 7 David 2005-11-09 16:42:21 UTC

Created attachment 6522 [details]
2.6.14 kernel system software info.

Output of ver_linux script.

Comment 8 David 2005-11-11 13:03:32 UTC

>On Friday 11 November 2005 14:33, Oliver Neukum wrote:
> 
> I can sort of replicate it, but I get a different error.
> Please recompile the driver with MTS_DO_DEBUG defined and try again.
> 
> 	Regards
> 		Oliver
> 

Enabled MTS_DO_DEBUG in microtek.c . Quite a lot of info:
(Kern.log after plugging in scanner)

Nov 11 15:03:18 decibels hub 1-0:1.0: state 5 ports 8 chg 0000 evt 0004
Nov 11 15:03:18 decibels ehci_hcd 0000:00:02.2: GetStatus port 2 status 001002 POWER sig=se0 CSC
Nov 11 15:03:18 decibels hub 1-0:1.0: port 2, status 0100, change 0001, 12 Mb/s
Nov 11 15:03:18 decibels hub 1-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
Nov 11 15:03:18 decibels hub 1-0:1.0: state 5 ports 8 chg 0000 evt 0004
Nov 11 15:03:18 decibels ehci_hcd 0000:00:02.2: GetStatus port 2 status 001803 POWER sig=j CSC CONNECT
Nov 11 15:03:18 decibels hub 1-0:1.0: port 2, status 0501, change 0001, 480 Mb/s
Nov 11 15:03:18 decibels hub 1-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x501
Nov 11 15:03:18 decibels ehci_hcd 0000:00:02.2: port 2 full speed --> companion
Nov 11 15:03:18 decibels ehci_hcd 0000:00:02.2: GetStatus port 2 status 003001 POWER OWNER sig=se0 CONNECT
Nov 11 15:03:19 decibels hub 2-0:1.0: state 5 ports 4 chg 0000 evt 0004
Nov 11 15:03:19 decibels ohci_hcd 0000:00:02.0: GetStatus roothub.portstatus [1] = 0x00010101 CSC PPS CCS
Nov 11 15:03:19 decibels hub 2-0:1.0: port 2, status 0101, change 0001, 12 Mb/s
Nov 11 15:03:19 decibels hub 2-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x101
Nov 11 15:03:19 decibels ohci_hcd 0000:00:02.0: GetStatus roothub.portstatus [1] = 0x00100103 PRSC PPS PES CCS
Nov 11 15:03:19 decibels usb 2-2: new full speed USB device using ohci_hcd and address 3
Nov 11 15:03:19 decibels ohci_hcd 0000:00:02.0: GetStatus roothub.portstatus [1] = 0x00100103 PRSC PPS PES CCS
Nov 11 15:03:19 decibels usb 2-2: new device strings: Mfr=0, Product=0, SerialNumber=0
Nov 11 15:03:19 decibels DEV: registering device: ID = '2-2'
Nov 11 15:03:19 decibels usb 2-2: hotplug
Nov 11 15:03:19 decibels PM: Adding info for usb:2-2
Nov 11 15:03:19 decibels bus usb: add device 2-2
Nov 11 15:03:19 decibels bound device '2-2' to driver 'usb'
Nov 11 15:03:19 decibels usb 2-2: adding 2-2:1.0 (config #1, interface 0)
Nov 11 15:03:19 decibels DEV: registering device: ID = '2-2:1.0'
Nov 11 15:03:19 decibels usb 2-2:1.0: hotplug
Nov 11 15:03:19 decibels PM: Adding info for usb:2-2:1.0
Nov 11 15:03:19 decibels bus usb: add device 2-2:1.0
Nov 11 15:03:19 decibels usb: Matched Device 2-2:1.0 with Driver microtekX6
Nov 11 15:03:19 decibels microtekX6 2-2:1.0: usb_probe_interface
Nov 11 15:03:19 decibels microtekX6 2-2:1.0: usb_probe_interface - got id
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:714 (mts_usb_probe)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): usb-device descriptor at 3c4e1000
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): product id = 0xb6, vendor id = 0x5da
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:721 (mts_usb_probe)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:725 (mts_usb_probe)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): found model Scanmaker V6UPL
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): model Scanmaker V6UPL is not known to be fully supported, reports welcome!
Nov 11 15:03:19 decibels scsi0 : microtekX6
Nov 11 15:03:19 decibels DEV: registering device: ID = 'host0'
Nov 11 15:03:19 decibels PM: Adding info for No Bus:host0
Nov 11 15:03:19 decibels CLASS: registering class device: ID = 'host0'
Nov 11 15:03:19 decibels class_hotplug - name = host0
Nov 11 15:03:19 decibels DEV: registering device: ID = 'target0:0:0'
Nov 11 15:03:19 decibels PM: Adding info for No Bus:target0:0:0
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:587 (mts_scsi_queuecommand)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command INQUIRY (6 bytes)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3):   12 00 00 00 24 00 9d 00 96 98
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0101
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:534 (mts_build_transfer_context)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Using scatter/gather
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfering from desc->ep_response == 2
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:455 (mts_command_done)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfer = 0x2e0dd200 context = 0x2d9f5390
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): status = 0x0 data-length = 0x24 sent = 0x6
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0301
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:376 (mts_int_submit_urb)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfer = 0x2e0dd200 context = 0x2d9f5390
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): status = 0x0 data-length = 0x24 sent = 0x6
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0301
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:496 (mts_do_sg)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfer = 0x2e0dd200 context = 0x2d9f5390
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): status = 0x0 data-length = 0x24 sent = 0x24
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Processing fragment 0 of 1
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:376 (mts_int_submit_urb)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfer = 0x2e0dd200 context = 0x2d9f5390
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): status = 0x0 data-length = 0x24 sent = 0x24
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels ohci_hcd 0000:00:02.0: urb ffff81002e0dd200 path 2 ep2in 83160000 cc 8 --> status -75
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:496 (mts_do_sg)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfer = 0x2e0dd200 context = 0x2d9f5390
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): status = 0xffffffb5 data-length = 0x24 sent = 0x0
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Processing fragment 1 of 1
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:402 (mts_transfer_cleanup)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfer = 0x2e0dd200 context = 0x2d9f5390
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): status = 0xffffffb5 data-length = 0x24 sent = 0x0
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:376 (mts_int_submit_urb)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfer = 0x2e0dd200 context = 0x2d9f5390
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): status = 0xffffffb5 data-length = 0x24 sent = 0x0
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:587 (mts_scsi_queuecommand)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command INQUIRY (6 bytes)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3):   12 00 00 00 24 00 9d 00 96 98
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:534 (mts_build_transfer_context)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Using scatter/gather
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfering from desc->ep_response == 2
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): error -22 submitting URB
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:587 (mts_scsi_queuecommand)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command INQUIRY (6 bytes)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3):   12 00 00 00 24 00 9d 00 96 98
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:534 (mts_build_transfer_context)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Using scatter/gather
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfering from desc->ep_response == 2
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): error -22 submitting URB
Nov 11 15:03:19 decibels PM: Removing info for No Bus:target0:0:0
Nov 11 15:03:19 decibels DEV: registering device: ID = 'target0:0:1'
Nov 11 15:03:19 decibels PM: Adding info for No Bus:target0:0:1
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:587 (mts_scsi_queuecommand)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command INQUIRY (6 bytes)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3):   12 00 00 00 24 00 b4 00 17 5a
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command to LUN=0 ID=1 CHANNEL=0 from SCSI layer
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): this device doesn't exist
Nov 11 15:03:19 decibels PM: Removing info for No Bus:target0:0:1
Nov 11 15:03:19 decibels DEV: registering device: ID = 'target0:0:2'
Nov 11 15:03:19 decibels PM: Adding info for No Bus:target0:0:2
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:587 (mts_scsi_queuecommand)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command INQUIRY (6 bytes)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3):   12 00 00 00 24 00 4f 00 4b 51
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command to LUN=0 ID=2 CHANNEL=0 from SCSI layer
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): this device doesn't exist
Nov 11 15:03:19 decibels PM: Removing info for No Bus:target0:0:2
Nov 11 15:03:19 decibels DEV: registering device: ID = 'target0:0:3'
Nov 11 15:03:19 decibels PM: Adding info for No Bus:target0:0:3
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:587 (mts_scsi_queuecommand)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command INQUIRY (6 bytes)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3):   12 00 00 00 24 00 00 00 00 00
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command to LUN=0 ID=3 CHANNEL=0 from SCSI layer
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): this device doesn't exist
Nov 11 15:03:19 decibels PM: Removing info for No Bus:target0:0:3
Nov 11 15:03:19 decibels DEV: registering device: ID = 'target0:0:4'
Nov 11 15:03:19 decibels PM: Adding info for No Bus:target0:0:4
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:587 (mts_scsi_queuecommand)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command INQUIRY (6 bytes)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3):   12 00 00 00 24 00 10 00 00 00
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command to LUN=0 ID=4 CHANNEL=0 from SCSI layer
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): this device doesn't exist
Nov 11 15:03:19 decibels PM: Removing info for No Bus:target0:0:4
Nov 11 15:03:19 decibels DEV: registering device: ID = 'target0:0:5'
Nov 11 15:03:19 decibels PM: Adding info for No Bus:target0:0:5
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:587 (mts_scsi_queuecommand)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command INQUIRY (6 bytes)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3):   12 00 00 00 24 00 00 00 00 00
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command to LUN=0 ID=5 CHANNEL=0 from SCSI layer
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): this device doesn't exist
Nov 11 15:03:19 decibels PM: Removing info for No Bus:target0:0:5
Nov 11 15:03:19 decibels DEV: registering device: ID = 'target0:0:6'
Nov 11 15:03:19 decibels PM: Adding info for No Bus:target0:0:6
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:587 (mts_scsi_queuecommand)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command INQUIRY (6 bytes)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3):   12 00 00 00 24 00 00 00 00 00
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command to LUN=0 ID=6 CHANNEL=0 from SCSI layer
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): this device doesn't exist
Nov 11 15:03:19 decibels PM: Removing info for No Bus:target0:0:6
Nov 11 15:03:19 decibels DEV: registering device: ID = 'target0:0:7'
Nov 11 15:03:19 decibels PM: Adding info for No Bus:target0:0:7
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:587 (mts_scsi_queuecommand)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command INQUIRY (6 bytes)
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3):   12 00 00 00 24 00 cc aa aa 2a
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command to LUN=0 ID=7 CHANNEL=0 from SCSI layer
Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): this device doesn't exist
Nov 11 15:03:19 decibels PM: Removing info for No Bus:target0:0:7
Nov 11 15:03:19 decibels bound device '2-2:1.0' to driver 'microtekX6'
Nov 11 15:03:19 decibels usb: Bound Device 2-2:1.0 to Driver microtekX6
Nov 11 15:03:19 decibels CLASS: registering class device: ID = 'usbdev2.3'
Nov 11 15:03:19 decibels class_hotplug - name = usbdev2.3
Nov 11 15:03:19 decibels hub 2-0:1.0: state 5 ports 4 chg 0000 evt 0004

Comment 9 Alan Stern 2005-11-11 13:51:54 UTC

On Fri, 11 Nov 2005, decibels wrote:

> >On Friday 11 November 2005 14:33, Oliver Neukum wrote:
> > 
> > I can sort of replicate it, but I get a different error.
> > Please recompile the driver with MTS_DO_DEBUG defined and try again.
> > 
> > 	Regards
> > 		Oliver
> > 
> 
> Enabled MTS_DO_DEBUG in microtek.c . Quite a lot of info:
> (Kern.log after plugging in scanner)

Correct me if I'm wrong:

> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Command INQUIRY (6 bytes)
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3):   12 00 00 00 24 00 9d 00 96 98
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0101
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:534 (mts_build_transfer_context)
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Using scatter/gather
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfering from desc->ep_response == 2
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:455 (mts_command_done)

This is the callback after sending the CDB.  Note that the driver does DMA
from a non-aligned buffer.

> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfer = 0x2e0dd200 context = 0x2d9f5390
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): status = 0x0 data-length = 0x24 sent = 0x6
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0301
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:376 (mts_int_submit_urb)

This is about to submit the URB to transfer the INQUIRY data.

> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfer = 0x2e0dd200 context = 0x2d9f5390
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): status = 0x0 data-length = 0x24 sent = 0x6
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0301
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:496 (mts_do_sg)

This is the callback.  Note that fragment 0 of a 1-fragment sg list has
just been sent.  Note also the use of page_address, which will fail for
buffers in high memory.

> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfer = 0x2e0dd200 context = 0x2d9f5390
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): status = 0x0 data-length = 0x24 sent = 0x24
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): Processing fragment 0 of 1
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): got to drivers/usb/image/microtek.c:376 (mts_int_submit_urb)

Here the driver is about to submit a 0-length URB for the non-existent
second fragment of a 1-fragment sg list.

> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): transfer = 0x2e0dd200 context = 0x2d9f5390
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): status = 0x0 data-length = 0x24 sent = 0x24
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): desc at 0x2d9f5340: toggle = 0305
> Nov 11 15:03:19 decibels microtek usb (rev 0.4.3): ep_out=c0008300 ep_response=c0010380 ep_image=c0018380
> Nov 11 15:03:19 decibels ohci_hcd 0000:00:02.0: urb ffff81002e0dd200 path 2 ep2in 83160000 cc 8 --> status -75

Not unnaturally, the device sends back more than 0 bytes, and you get 
-EOVERFLOW.

It looks like the problem is in mts_command_done.  It doesn't expect to 
deal with an sg list containing only one element.

Alan Stern

Comment 10 David 2005-11-11 14:09:40 UTC

Created attachment 6548 [details]
2.6.14 kernel system with more debug enabled in drivers.

MTS_DO_DEBUG in microtek.c and OHCI_VERBOSE_DEBUG in ohci-hcd.c enabled.
Results after plugging scanner in.

Comment 11 Anonymous Emailer 2005-11-12 08:00:01 UTC

Reply-To: oliver@neukum.org

Am Freitag, 11. November 2005 22:56 schrieb Alan Stern:
> It looks like the problem is in mts_command_done.

Comment 12 Alan Stern 2005-11-12 08:44:47 UTC

On Sat, 12 Nov 2005, Oliver Neukum wrote:

> Am Freitag, 11. November 2005 22:56 schrieb Alan Stern:
> > It looks like the problem is in mts_command_done.

Comment 13 David 2005-11-12 09:32:23 UTC

On Saturday 12 November 2005 10:49, Alan Stern wrote:
> On Sat, 12 Nov 2005, Oliver Neukum wrote:
> 
> > Am Freitag, 11. November 2005 22:56 schrieb Alan Stern:
> > > It looks like the problem is in mts_command_done.

Comment 14 Greg Kroah-Hartman 2005-11-14 21:37:34 UTC

So can someone send me a patch to fix this?

Comment 15 David 2005-11-15 04:27:16 UTC

Created attachment 6591 [details]
Patch for Microtek driver: error -22 submitting URB

Tested patch supplied by Oliver Neukum.
Works perfectly. Is being submitted for acceptance on usb-devel.
Patches microtek.c & microtek.h

Comment 16 Daniel Drake 2005-11-24 16:34:58 UTC

This can be closed as CODE_FIX because the fix is in Linus' tree.

Comment 17 Greg Kroah-Hartman 2005-11-24 19:46:50 UTC

Done.

Note You need to log in before you can comment on or make changes to this bug.