Process of scanning breaks

Andrew Muhametshin andrew at dobrohot.org
Mon May 7 17:01:31 UTC 2007


Hello.

It is not possible to take advantage of the scanner(Epson Pervection 
2400 Photo).
Scanning constantly breaks and it is necessary to disconnect and anew to 
include the scanner.
I start process of scanning... Аfter a while, process interrupts. 
Process of scanning comes to an end successfully very seldom.

Usually, successful scanning probably only on small images which can be 
transferred from the scanner in a computer for time, without a 
suspension of process of scanning for data transmission. But sometimes, 
lags occur even during preliminary scanning.


=================START=====================================
# uname -a
FreeBSD host01 6.2-STABLE FreeBSD 6.2-STABLE #1: Sun May  6 08:11:31 MSD 
2007     
root at inspirra.localdomain:/SHARED/obj.host01/usr/src/sys/HOST01  i386

# grep -E "^u|eh" /var/run/dmesg.boot
ehci0: <VIA VT6202 USB 2.0 controller> mem 0xdf040000-0xdf0400ff at 
device 16.2 on pci0
ehci0: [GIANT-LOCKED]
usb0: EHCI version 0.95
usb0: wrong number of companions (2 != 0)
usb0: <VIA VT6202 USB 2.0 controller> on ehci0
usb0: USB revision 2.0
uhub0: VIA EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub0: 4 ports with 4 removable, self powered
ugen0: EPSON EPSON Scanner, rev 2.00/1.00, addr 2

# scanimage --version
scanimage (sane-backends) 1.0.18; backend version 1.0.18

# scanimage -L
device `epson:libusb:/dev/usb0:/dev/ugen0' is a Epson GT-9300 flatbed 
scanner

# sysctl hw.usb.debug=15

# scanimage -v -p --quick-format CD --resolution 500 --mode Color >  
/dev/null
scanimage: rounded value of resolution from 500 to 480
scanimage: scanning image of size 2264x2268 pixels at 24 bits/pixel
scanimage: acquiring RGB frame
Progress: 18.7%
scanimage: min/max graylevel value = 64/255
scanimage: sane_read: Error during device I/O
=================The end==================================

Completely, debugging messages can be loaded from here: 
http://www.dobrohot.org/hw.usb.debug.txt

=================START=====================================
May  7 16:25:55 host01 kernel: usb_event_thread: woke up
May  7 16:25:55 host01 kernel: usb_discover
May  7 16:26:05 host01 kernel: usbd_alloc_xfer() = 0xc37ab000
May  7 16:26:05 host01 kernel: usbd_transfer: xfer=0xc37ab000, flags=6, 
pipe=0xc3752800, running=0
May  7 16:26:05 host01 kernel: usbd_dump_queue: pipe=0xc3752800
May  7 16:26:05 host01 kernel: usb_allocmem: use frag=0xc37a3c40 size=2
May  7 16:26:05 host01 kernel: usb_insert_transfer: pipe=0xc3752800 
running=0 timeout=5000
May  7 16:26:05 host01 kernel: usb_transfer_complete: pipe=0xc3752800 
xfer=0xc37ab000 status=0 actlen=2
May  7 16:26:05 host01 kernel: usb_freemem: frag=0xc37a3c40
May  7 16:26:05 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:26:05 host01 kernel: usbd_start_next: pipe=0xc3752800, xfer=0
May  7 16:26:05 host01 kernel: usbd_free_xfer: 0xc37ab000
May  7 16:26:05 host01 kernel: usbd_alloc_xfer() = 0xc37ab000
<...>skip 84 lines<...>
May  7 16:26:05 host01 kernel: usbd_alloc_xfer() = 0xc37ab000
May  7 16:26:05 host01 kernel: usbd_transfer: xfer=0xc37ab000, flags=6, 
pipe=0xc3f06780, running=0
May  7 16:26:05 host01 kernel: usbd_dump_queue: pipe=0xc3f06780
May  7 16:26:05 host01 kernel: usb_allocmem: use frag=0xc37a3c40 size=32
May  7 16:26:05 host01 kernel: usb_insert_transfer: pipe=0xc3f06780 
running=0 timeout=1000
May  7 16:26:05 host01 kernel: usb_schedsoftintr: polling=0
May  7 16:26:05 host01 kernel: usb_transfer_complete: pipe=0xc3f06780 
xfer=0xc37ab000 status=0 actlen=32
May  7 16:26:05 host01 kernel: usb_freemem: frag=0xc37a3c40
May  7 16:26:05 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:26:05 host01 kernel: usbd_start_next: pipe=0xc3f06780, xfer=0
May  7 16:26:05 host01 kernel: usbd_free_xfer: 0xc37ab000
May  7 16:26:05 host01 kernel: All threads purged from ugen0.1
May  7 16:26:05 host01 kernel: All threads purged from ugen0.2
May  7 16:26:05 host01 kernel: usbd_open_pipe: iface=0xc4272380 
address=0x2 flags=0x0
May  7 16:26:05 host01 kernel: usbd_setup_pipe: dev=0xc44eec00 
iface=0xc4272380 ep=0xc3a32ccc pipe=0xd58d5944
May  7 16:26:05 host01 kernel: usbd_alloc_xfer() = 0xc37ab000
May  7 16:26:05 host01 kernel: usbd_bulk_transfer: start transfer 2 bytes
May  7 16:26:05 host01 kernel: usbd_transfer: xfer=0xc37ab000, flags=0, 
pipe=0xc44ee080, running=0
May  7 16:26:05 host01 kernel: usbd_dump_queue: pipe=0xc44ee080
May  7 16:26:05 host01 kernel: usb_allocmem: use frag=0xc37a3c40 size=2
May  7 16:26:05 host01 kernel: usb_insert_transfer: pipe=0xc44ee080 
running=0 timeout=0
May  7 16:26:05 host01 kernel: usb_schedsoftintr: polling=0
May  7 16:26:05 host01 kernel: usb_transfer_complete: pipe=0xc44ee080 
xfer=0xc37ab000 status=0 actlen=2
<...>skip 7235 lines<...>
May  7 16:26:33 host01 kernel: usb_transfer_complete: pipe=0xc44f1780 
xfer=0xc37ab000 status=0 actlen=1024
May  7 16:26:33 host01 kernel: usb_freemem: large free
May  7 16:26:33 host01 kernel: usb_block_freemem: size=4096
May  7 16:26:33 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:26:33 host01 kernel: usbd_start_next: pipe=0xc44f1780, xfer=0
May  7 16:26:33 host01 kernel: usbd_bulk_transfer: transferred 1024
May  7 16:26:33 host01 kernel: usbd_bulk_transfer: start transfer 1024 bytes
May  7 16:26:33 host01 kernel: usbd_transfer: xfer=0xc37ab000, flags=4, 
pipe=0xc44f1780, running=0
May  7 16:26:33 host01 kernel: usbd_dump_queue: pipe=0xc44f1780
May  7 16:26:33 host01 kernel: usb_allocmem: large alloc 1024
May  7 16:26:33 host01 kernel: usb_block_allocmem: size=4096 align=1
May  7 16:26:33 host01 kernel: usb_block_allocmem: free list size=4096
May  7 16:26:33 host01 kernel: usb_insert_transfer: pipe=0xc44f1780 
running=0 timeout=30000
May  7 16:26:33 host01 kernel: usb_schedsoftintr: polling=0
May  7 16:26:33 host01 kernel: usb_transfer_complete: pipe=0xc44f1780 
xfer=0xc37ab000 status=0 actlen=1024
May  7 16:26:33 host01 kernel: usb_freemem: large free
May  7 16:26:33 host01 kernel: usb_block_freemem: size=4096
May  7 16:26:33 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:26:33 host01 kernel: usbd_start_next: pipe=0xc44f1780, xfer=0
May  7 16:26:33 host01 kernel: usbd_bulk_transfer: transferred 1024
May  7 16:26:33 host01 kernel: usbd_bulk_transfer: start transfer 1024 bytes
May  7 16:26:33 host01 kernel: usbd_transfer: xfer=0xc37ab000, flags=4, 
pipe=0xc44f1780, running=0
May  7 16:26:33 host01 kernel: usbd_dump_queue: pipe=0xc44f1780
May  7 16:26:33 host01 kernel: usb_allocmem: large alloc 1024
May  7 16:26:33 host01 kernel: usb_block_allocmem: size=4096 align=1
May  7 16:26:33 host01 kernel: usb_block_allocmem: free list size=4096
May  7 16:26:33 host01 kernel: usb_insert_transfer: pipe=0xc44f1780 
running=0 timeout=30000

************Approximately in this place, process of scanning has 
broken.*******************

May  7 16:26:33 host01 kernel: usb_schedsoftintr: polling=0
May  7 16:26:33 host01 kernel: usb_transfer_complete: pipe=0xc44f1780 
xfer=0xc37ab000 status=0 actlen=1024
May  7 16:26:33 host01 kernel: usb_freemem: large free
May  7 16:26:33 host01 kernel: usb_block_freemem: size=4096
May  7 16:26:33 host01 kernel: usb_transfer_complete: repeat=0 new head
<...>skip 58 lines<...>
May  7 16:27:00 host01 kernel: usb_allocmem: use frag=0xc37a3c40 size=4
May  7 16:27:00 host01 kernel: usb_insert_transfer: pipe=0xc44f1780 
running=0 timeout=30000
May  7 16:27:00 host01 kernel: usb_transfer_complete: pipe=0xc3752600 
xfer=0xc37ab100 status=0 actlen=1
May  7 16:27:00 host01 kernel: usb_transfer_complete: repeat=1 new 
head=0xc37ab100
May  7 16:27:00 host01 kernel: usb_needs_explore
May  7 16:27:00 host01 kernel: usb_schedsoftintr: polling=0
May  7 16:27:00 host01 kernel: usb_transfer_complete: pipe=0xc44f1780 
xfer=0xc37ab000 status=13 actlen=0
May  7 16:27:00 host01 kernel: usb_freemem: frag=0xc37a3c40
May  7 16:27:00 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:27:00 host01 kernel: usbd_start_next: pipe=0xc44f1780, xfer=0
May  7 16:27:00 host01 kernel: usbd_bulk_transfer: transferred 0
May  7 16:27:00 host01 kernel: usbd_bulk_transfer: error=13
May  7 16:27:00 host01 kernel: usbd_clear_endpoint_stall
May  7 16:27:00 host01 kernel: usbd_alloc_xfer() = 0xc375bc00
May  7 16:27:00 host01 kernel: usbd_transfer: xfer=0xc375bc00, flags=2, 
pipe=0xc3f06780, running=0
May  7 16:27:00 host01 kernel: usbd_dump_queue: pipe=0xc3f06780
May  7 16:27:00 host01 kernel: usb_insert_transfer: pipe=0xc3f06780 
running=0 timeout=5000
May  7 16:27:00 host01 kernel: usb_event_thread: woke up
May  7 16:27:00 host01 kernel: usb_discover
May  7 16:27:00 host01 kernel: usbd_alloc_xfer() = 0xc4325100
May  7 16:27:00 host01 kernel: usbd_transfer: xfer=0xc4325100, flags=2, 
pipe=0xc3752800, running=0
May  7 16:27:00 host01 kernel: usbd_dump_queue: pipe=0xc3752800
May  7 16:27:00 host01 kernel: usb_allocmem: use frag=0xc37a3c40 size=4
May  7 16:27:00 host01 kernel: usb_insert_transfer: pipe=0xc3752800 
running=0 timeout=5000
May  7 16:27:00 host01 kernel: usb_transfer_complete: pipe=0xc3752800 
xfer=0xc4325100 status=0 actlen=4
May  7 16:27:00 host01 kernel: usb_freemem: frag=0xc37a3c40
May  7 16:27:00 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:27:00 host01 kernel: usbd_start_next: pipe=0xc3752800, xfer=0
May  7 16:27:00 host01 kernel: usbd_free_xfer: 0xc4325100
May  7 16:27:00 host01 kernel: usbd_alloc_xfer() = 0xc4325100
May  7 16:27:00 host01 kernel: usbd_transfer: xfer=0xc4325100, flags=2, 
pipe=0xc3752800, running=0
May  7 16:27:00 host01 kernel: usbd_dump_queue: pipe=0xc3752800
May  7 16:27:00 host01 kernel: usb_allocmem: use frag=0xc37a3c40 size=4
May  7 16:27:00 host01 kernel: usb_insert_transfer: pipe=0xc3752800 
running=0 timeout=5000
May  7 16:27:00 host01 kernel: usb_transfer_complete: pipe=0xc3752800 
xfer=0xc4325100 status=0 actlen=4
May  7 16:27:00 host01 kernel: usb_freemem: frag=0xc37a3c40
May  7 16:27:00 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:27:00 host01 kernel: usbd_start_next: pipe=0xc3752800, xfer=0
May  7 16:27:00 host01 kernel: usbd_free_xfer: 0xc4325100
May  7 16:27:00 host01 kernel: usbd_alloc_xfer() = 0xc4325100
May  7 16:27:00 host01 kernel: usbd_transfer: xfer=0xc4325100, flags=2, 
pipe=0xc3752800, running=0
May  7 16:27:00 host01 kernel: usbd_dump_queue: pipe=0xc3752800
May  7 16:27:00 host01 kernel: usb_insert_transfer: pipe=0xc3752800 
running=0 timeout=5000
May  7 16:27:00 host01 kernel: usb_transfer_complete: pipe=0xc3752800 
xfer=0xc4325100 status=0 actlen=0
May  7 16:27:00 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:27:00 host01 kernel: usbd_start_next: pipe=0xc3752800, xfer=0
May  7 16:27:00 host01 kernel: usbd_free_xfer: 0xc4325100
May  7 16:27:00 host01 kernel: usbd_alloc_xfer() = 0xc4325100
May  7 16:27:00 host01 kernel: usbd_transfer: xfer=0xc4325100, flags=2, 
pipe=0xc3752800, running=0
May  7 16:27:00 host01 kernel: usbd_dump_queue: pipe=0xc3752800
May  7 16:27:00 host01 kernel: usb_insert_transfer: pipe=0xc3752800 
running=0 timeout=5000
May  7 16:27:00 host01 kernel: usb_transfer_complete: pipe=0xc3752800 
xfer=0xc4325100 status=0 actlen=0
May  7 16:27:00 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:27:00 host01 kernel: usbd_start_next: pipe=0xc3752800, xfer=0
May  7 16:27:00 host01 kernel: usbd_free_xfer: 0xc4325100
May  7 16:27:00 host01 kernel: uhub_disconnect: up=0xc37526c4 
dev=0xc44eec00 port=2
May  7 16:27:00 host01 kernel: usb_disconnect_port: disconnect subdevs
May  7 16:27:00 host01 kernel: ugen0: at uhub0 port 2 (addr 2) disconnected
May  7 16:27:00 host01 kernel: usbd_ar_pipe: pipe=0xc44f1780
May  7 16:27:00 host01 kernel: usbd_dump_queue: pipe=0xc44f1780
May  7 16:27:00 host01 kernel: usbd_ar_pipe: pipe=0xc44f1880
May  7 16:27:00 host01 kernel: usbd_dump_queue: pipe=0xc44f1880
May  7 16:27:00 host01 kernel: All threads purged from ugen0.2
May  7 16:27:00 host01 kernel: Purging 2 threads from ugen0.1
May  7 16:27:00 host01 kernel: usbd_ar_pipe: pipe=0xc44f1780
May  7 16:27:00 host01 kernel: usbd_dump_queue: pipe=0xc44f1780
May  7 16:27:00 host01 kernel: usb_schedsoftintr: polling=0
May  7 16:27:00 host01 kernel: usb_transfer_complete: pipe=0xc3f06780 
xfer=0xc375bc00 status=13 actlen=0
May  7 16:27:00 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:27:00 host01 kernel: usbd_start_next: pipe=0xc3f06780, xfer=0
May  7 16:27:00 host01 kernel: usbd_free_xfer: 0xc375bc00
May  7 16:27:00 host01 kernel: usbd_free_xfer: 0xc37ab000
May  7 16:27:00 host01 kernel: All threads purged from ugen0.1
May  7 16:27:00 host01 kernel: All threads purged from ugen0
May  7 16:27:00 host01 kernel: ugen0: detached
May  7 16:27:00 host01 kernel: usbd_ar_pipe: pipe=0xc3f06780
May  7 16:27:00 host01 kernel: usbd_dump_queue: pipe=0xc3f06780
May  7 16:27:00 host01 kernel: usbd_alloc_xfer() = 0xc37ab000
May  7 16:27:00 host01 kernel: usbd_transfer: xfer=0xc37ab000, flags=2, 
pipe=0xc3752800, running=0
May  7 16:27:00 host01 kernel: usbd_dump_queue: pipe=0xc3752800
May  7 16:27:00 host01 kernel: usb_insert_transfer: pipe=0xc3752800 
running=0 timeout=5000
May  7 16:27:00 host01 kernel: usb_transfer_complete: pipe=0xc3752800 
xfer=0xc37ab000 status=0 actlen=0
May  7 16:27:00 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:27:00 host01 kernel: usbd_start_next: pipe=0xc3752800, xfer=0
May  7 16:27:00 host01 kernel: usbd_free_xfer: 0xc37ab000
May  7 16:27:00 host01 kernel: usbd_alloc_xfer() = 0xc37ab000
May  7 16:27:00 host01 kernel: usbd_transfer: xfer=0xc37ab000, flags=2, 
pipe=0xc3752800, running=0
May  7 16:27:00 host01 kernel: usbd_dump_queue: pipe=0xc3752800
May  7 16:27:00 host01 kernel: usb_allocmem: use frag=0xc37a3c40 size=4
May  7 16:27:00 host01 kernel: usb_insert_transfer: pipe=0xc3752800 
running=0 timeout=5000
May  7 16:27:00 host01 kernel: usb_transfer_complete: pipe=0xc3752800 
xfer=0xc37ab000 status=0 actlen=4
May  7 16:27:00 host01 kernel: usb_freemem: frag=0xc37a3c40
May  7 16:27:00 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:27:00 host01 kernel: usbd_start_next: pipe=0xc3752800, xfer=0
May  7 16:27:00 host01 kernel: usbd_free_xfer: 0xc37ab000
May  7 16:27:00 host01 kernel: usbd_alloc_xfer() = 0xc37ab000
May  7 16:27:00 host01 kernel: usbd_transfer: xfer=0xc37ab000, flags=2, 
pipe=0xc3752800, running=0
May  7 16:27:00 host01 kernel: usbd_dump_queue: pipe=0xc3752800
May  7 16:27:00 host01 kernel: usb_allocmem: use frag=0xc37a3c40 size=4
May  7 16:27:00 host01 kernel: usb_insert_transfer: pipe=0xc3752800 
running=0 timeout=5000
May  7 16:27:00 host01 kernel: usb_transfer_complete: pipe=0xc3752800 
xfer=0xc37ab000 status=0 actlen=4
May  7 16:27:00 host01 kernel: usb_freemem: frag=0xc37a3c40
May  7 16:27:00 host01 kernel: usb_transfer_complete: repeat=0 new head=0
May  7 16:27:00 host01 kernel: usbd_start_next: pipe=0xc3752800, xfer=0
May  7 16:27:00 host01 kernel: usbd_free_xfer: 0xc37ab000
May  7 16:27:00 host01 kernel: usb_schedsoftintr: polling=0
May  7 16:27:59 host01 kernel: usb_event_thread: woke up
May  7 16:27:59 host01 kernel: usb_discover
=================The end===================================





More information about the freebsd-usb mailing list