[sane-devel] test results for epson with sane-backends-1.0.14-pre3

Fri, 30 Apr 2004 22:45:05 +0200

--qMm9M+Fa2AknHoGS
Content-Type: text/plain; charset=us-ascii
Content-Disposition: inline

I tried sane-backends-1.0.14-pre3 with SCSI and USB and an EPSON-1640.

With kernel-2.6.6-rc3 and USB I was able to scan using flatbed.
But as soon as I add --source "Automatic Document Feeder" I get this
messages from the kernel ..

kernel: usb 3-1: bulk timeout on ep1in
kernel: usbfs: USBDEVFS_BULK failed dev 11 ep 0x81 len 1 ret -110

With kernel-2.6.5 using the SCSI-interface sane-backends-1.0.14-pre3
does not work (debug output attached), but sane-backends-1.0.12 does.

--
Klaus

--qMm9M+Fa2AknHoGS
Content-Type: text/plain; charset=us-ascii
Content-Disposition: attachment; filename="backends-1.0.14-epson-scsi"

XXXXX scanimage -d epson:/dev/sg3 --source "Automatic Document Feeder" ..
[sanei_debug] Setting debug level of sanei_scsi to 255.
[sanei_scsi] sanei_scsi_find_devices: vendor=EPSON model=(null) type=(null)
	bus=2 chan=0 id=5 lun=0  num=3
[sanei_scsi] lx_chk_id: 2,2  0,0  5,5  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg3
[sanei_debug] Setting debug level of sanei_scsi to 255.
[sanei_debug] Setting debug level of sanei_scsi to 255.
[sanei_debug] Setting debug level of sanei_scsi to 255.
[sanei_scsi] sanei_scsi_find_devices: vendor=(null) model=(null) type=Processor
	bus=2 chan=0 id=5 lun=0  num=3
[sanei_scsi] lx_chk_id: 2,2  0,0  5,5  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg3
[sanei_scsi] get_max_buffer_size for /dev/sg3: 131072
[sanei_scsi] sanei_scsi_open: sanei_scsi_max_request_size=131072 bytes
[sanei_scsi] sanei_scsi_open: SG driver version: 30530
[sanei_scsi] sanei_scsi_open_extended: using 131072 bytes as SCSI buffer
[sanei_scsi] trying to enable low level command queueing
[sanei_scsi] sanei_scsi_open: Host adapter queue depth: 2
[sanei_scsi] sanei_scsi_open: SG driver can change buffer size at run time
[sanei_scsi] sanei_scsi_open: low level command queueing enabled
[sanei_scsi] sanei_scsi_open: using new SG header structure
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=0 blen=36 dur=1ms sgat=0 op=0x12
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=0 blen=36 dur=1ms sgat=0 op=0x12
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=1 blen=2 dur=1ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=1 blen=2 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=2 blen=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=2 blen=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> act: id=3 blen=2 t_o/elap=120000/7ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=3 blen=2 dur=84ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=4 blen=4 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=4 blen=4 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=5 blen=106 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=5 blen=106 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=6 blen=2 dur=1ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=6 blen=2 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=7 blen=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=7 blen=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=8 blen=1 dur=1ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=8 blen=1 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=9 blen=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=9 blen=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=10 blen=2 dur=1ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=10 blen=2 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=11 blen=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=11 blen=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=12 blen=1 dur=1ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=12 blen=1 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=13 blen=1 dur=0ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=13 blen=1 dur=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=14 blen=2 dur=2ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=14 blen=2 dur=2ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=15 blen=4 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=15 blen=4 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=16 blen=2 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=16 blen=2 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=17 blen=2 dur=1ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=17 blen=2 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=18 blen=4 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=18 blen=4 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=19 blen=42 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40226008
[sanei_scsi] sanei_scsi.issue: 0x40226008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=19 blen=42 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_debug] Setting debug level of sanei_scsi to 255.
[sanei_scsi] sanei_scsi_open: SG driver version: 30530
[sanei_scsi] sanei_scsi_open_extended: using 131072 bytes as SCSI buffer
[sanei_scsi] trying to enable low level command queueing
[sanei_scsi] sanei_scsi_open: Host adapter queue depth: 2
[sanei_scsi] sanei_scsi_open: SG driver can change buffer size at run time
[sanei_scsi] sanei_scsi_open: low level command queueing enabled
[sanei_scsi] sanei_scsi_open: using new SG header structure
Scanning 2 pages, incrementing by 2, numbering from 1
Scanning page 1
[sanei_scsi] scsi_req_enter: entered 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=20 blen=2 dur=2ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=20 blen=2 dur=2ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=21 blen=1 dur=0ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=21 blen=1 dur=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=22 blen=1 dur=1ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=22 blen=1 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=23 blen=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=23 blen=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=24 blen=2 dur=1ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=24 blen=2 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=25 blen=4 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=25 blen=4 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=26 blen=42 dur=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=26 blen=42 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=27 blen=1 dur=1ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=27 blen=1 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=28 blen=1 dur=3ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x40224008
[sanei_scsi] sanei_scsi.issue: 0x40224008
dev_max(currently)=6 max_active_device=4 (origin 1)
 def_reserved_size=32768
 >>> device=sg3 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=96 excl=1
   FD(1): timeout=120000ms bufflen=131072 (res)sgat=4 low_dma=0
   cmd_q=1 f_packid=0 k_orphan=0 closed=0
     rb>> rcv: id=28 blen=1 dur=3ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] sanei_scsi_req_wait: SCSI command complained: Success
[sanei_scsi] sense buffer: f0 00 05 00 00 00 00 00 00 00 00 00 00 00 00 00
[sanei_scsi] target status: 02 host status: 0000 driver status: 0008
[sanei_scsi] sanei_scsi_req_wait: SG driver returned resid 1
[sanei_scsi]                      NOTE: This value may be bogus
scanimage: sane_start: Error during device I/O
Scanned page 1. (scanner status = 9)

--qMm9M+Fa2AknHoGS--