[sane-devel] Help with Canon 2700F

Wed Aug 16 09:23:57 UTC 2006

Am Dienstag, 15. August 2006 14:50 schrieb Johannes Meixner:
> Hello,
>
> On Aug 15 00:59 Ulrich Deiters wrote (shortened):
> > I have the same problem since I switched to Linux. Now I made sure
> > that canon.conf contains /dev/sg0 and /dev/scanner
> > AND I created a symbolic link: ln -s /dev/sg0 /dev/scanner.
> > Now "scanimage -L" sees my Canon scanner.
>
> Does this mean that it does not work when canon.conf contains
> either only "/dev/sg0" or only "scsi" as I suggested in
> https://bugzilla.novell.com/show_bug.cgi?id=177492#c7
I've a similar scanner - Canon FS2710 - and I did not get it running yet.
System is Debian Sarge: uname -a
2.6.8-3-686-smp #1 SMP Sat Jul 15 08:52:57 UTC 2006 i686 GNU/Linux
----------------------------
# cat /proc/scsi/scsi
Attached devices:
Host: scsi0 Channel: 00 Id: 00 Lun: 00
  Vendor: ATA      Model: ST3200822AS      Rev: 3.01
  Type:   Direct-Access                    ANSI SCSI revision: 05
Host: scsi2 Channel: 00 Id: 01 Lun: 00
  Vendor: CANON    Model: IX-27025E        Rev: 1.12
  Type:   Scanner                          ANSI SCSI revision: 02
Host: scsi2 Channel: 00 Id: 05 Lun: 00
  Vendor: EPSON    Model: Perfection1200   Rev: 1.03
  Type:   Processor                        ANSI SCSI revision: 02
Host: scsi3 Channel: 00 Id: 00 Lun: 00
  Vendor: USB2.0   Model: CardReader CF RW Rev: 0814
  Type:   Direct-Access                    ANSI SCSI revision: 02
Host: scsi3 Channel: 00 Id: 00 Lun: 01
  Vendor: USB2.0   Model: CardReader Combo Rev: 0814
  Type:   Direct-Access                    ANSI SCSI revision: 02
------------------------------------
and I tried your suggestions (link to /dev/scanner, comment out canon 
in /etc/sane.d/dll.conf)
The scanner is seen by sane-find-scanner:
------
found SCSI scanner "CANON IX-27025E 1.12" at /dev/scanner
found SCSI scanner "CANON IX-27025E 1.12" at /dev/sg1
found SCSI processor "EPSON Perfection1200 1.03" at /dev/sg2
-----
owner and group:
# ls -lah /dev/sg1
crw-rw----  1 root scanner 21, 1 2006-08-16 11:57 /dev/sg1
# ls -lah /dev/sg2
crw-rw----  1 root scanner 21, 2 2006-08-16 11:57 /dev/sg2
# ls -lah /dev/scanner
lrwxrwxrwx  1 root scanner 8 2006-08-16 10:02 /dev/scanner -> /dev/sg1

>
> If it does not work when canon.conf contains only "scsi",
no, it doesn't, not seen by scanimage -L (root and normal user),
scanimage -d canon:/dev/scanner 
scanimage: open of device canon:/dev/scanner failed: Error during device I/O

> I would like to get appropriate debug messages, see
> http://lists.alioth.debian.org/pipermail/sane-devel/2006-August/017475.html
>
debug messages - as root,
export SANE_DEBUG_DLL=4
export SANE_DEBUG_CANON=128
export SANE_DEBUG_SANEI_SCSI=255

(output is _very_ long, see attached scl_1017.dbg and scl_1018.dbg)
here is just the part I think is interesting - (from v.17):

quite at top:
------------
...
[sanei_debug] Setting debug level of sanei_scsi to 255.
[sanei_scsi] sanei_scsi_find_devices: vendor=(null) model=(null) type=Scanner
	bus=2 chan=0 id=1 lun=0  num=1
[sanei_scsi] lx_chk_id: 2,2  0,0  1,1  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg1
[sanei_scsi] get_max_buffer_size for /dev/sg1: 131072
[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=2
[sanei_scsi] lx_chk_id: 2,2  0,0  5,5  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg2
[sanei_scsi] get_max_buffer_size for /dev/sg2: 131072
[sanei_scsi] sanei_scsi_open: sanei_scsi_max_request_size=131072 bytes
[sanei_scsi] sanei_scsi_open: SG driver version: 30531
[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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x12
....
--------------
to me, it looks like that the (working) Epson scanner gives much more info 
than the canon does.

there are several blocks - all with this pattern - minimal info for canon, a 
lot of it for Epson

> I don't have such a scanner. Therefore without your help with debug
> messages it is not possible for me to get it working out of the box.

I already tried to debug (eclipse, cdt) the call - but I did not manage to 
have the source included when init() is called - the closest I got to, is 
that sense_handler in canon.c seems to be called with a null argument - seen 
in the debug output too - but here the mess has happened already.

I append the output produced by
/usr/local/bin/scanimage -d canon:/dev/sg1
as scd_1018.dbg.

Strange enough - I know it _did_ work some years ago.

>
> Kind Regards
> Johannes Meixner
> --
> SUSE LINUX Products GmbH, Maxfeldstrasse 5      Mail: jsmeix at suse.de
> 90409 Nuernberg, Germany                    WWW: http://www.suse.de/

thanks for your work

Lutz
-------------- next part --------------
[sanei_debug] Setting debug level of dll to 4.
[dll] sane_init: SANE dll backend version 1.0.11 from sane-backends 1.0.17
[dll] add_backend: adding backend `net'
[dll] add_backend: adding backend `epson'
[dll] add_backend: adding backend `v4l'
[dll] sane_get_devices
[dll] load: searching backend `v4l' in `/usr/lib/sane'
[dll] load: trying to load `/usr/lib/sane/libsane-v4l.so.1'
[dll] load: dlopen()ing `/usr/lib/sane/libsane-v4l.so.1'
[dll] init: initializing backend `v4l'
[dll] init: backend `v4l' is version 1.0.4
[dll] load: searching backend `epson' in `/usr/lib/sane'
[dll] load: trying to load `/usr/lib/sane/libsane-epson.so.1'
[dll] load: dlopen()ing `/usr/lib/sane/libsane-epson.so.1'
[dll] init: initializing backend `epson'
[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=2
[sanei_scsi] lx_chk_id: 2,2  0,0  5,5  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg2
[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=Scanner
	bus=2 chan=0 id=1 lun=0  num=1
[sanei_scsi] lx_chk_id: 2,2  0,0  1,1  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg1
[sanei_scsi] get_max_buffer_size for /dev/sg1: 131072
[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=2
[sanei_scsi] lx_chk_id: 2,2  0,0  5,5  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg2
[sanei_scsi] get_max_buffer_size for /dev/sg2: 131072
[sanei_scsi] sanei_scsi_open: sanei_scsi_max_request_size=131072 bytes
[sanei_scsi] sanei_scsi_open: SG driver version: 30531
[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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x12
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x12
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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/6ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=88ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=103 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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=103 dur=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=1ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=1 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=2 dur=0ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=2 dur=0ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=4 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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=4 dur=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=42 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 0x401e6008
[sanei_scsi] sanei_scsi.issue: 0x401e6008
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=42 dur=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[dll] init: backend `epson' is version 1.0.245
[dll] load: searching backend `net' in `/usr/lib/sane'
[dll] load: trying to load `/usr/lib/sane/libsane-net.so.1'
[dll] load: dlopen()ing `/usr/lib/sane/libsane-net.so.1'
[dll] init: initializing backend `net'
[dll] init: backend `net' is version 1.0.17
[dll] sane_get_devices: found 2 devices
[dll] sane_exit: exiting
[dll] sane_exit: calling backend `v4l's exit function
[dll] sane_exit: calling backend `epson's exit function
[dll] sane_exit: calling backend `net's exit function
[dll] sane_exit: finished
-------------- next part --------------
[sanei_debug] Setting debug level of dll to 4.
[dll] sane_init: SANE dll backend version 1.0.12 from sane-backends 1.0.18
[dll] add_backend: adding backend `canon'
[dll] add_backend: adding backend `epson'
[dll] add_backend: adding backend `v4l'
[dll] sane_get_devices
[dll] load: searching backend `v4l' in `/usr/local/lib/sane'
[dll] load: trying to load `/usr/local/lib/sane/libsane-v4l.so.1'
[dll] load: dlopen()ing `/usr/local/lib/sane/libsane-v4l.so.1'
[dll] init: initializing backend `v4l'
[dll] init: backend `v4l' is version 1.0.4
[dll] load: searching backend `epson' in `/usr/local/lib/sane'
[dll] load: trying to load `/usr/local/lib/sane/libsane-epson.so.1'
[dll] load: dlopen()ing `/usr/local/lib/sane/libsane-epson.so.1'
[dll] init: initializing backend `epson'
[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=2
[sanei_scsi] lx_chk_id: 2,2  0,0  5,5  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg2
[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=Scanner
	bus=2 chan=0 id=1 lun=0  num=1
[sanei_scsi] lx_chk_id: 2,2  0,0  1,1  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg1
[sanei_scsi] get_max_buffer_size for /dev/sg1: 131072
[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=2
[sanei_scsi] lx_chk_id: 2,2  0,0  5,5  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg2
[sanei_scsi] get_max_buffer_size for /dev/sg2: 131072
[sanei_scsi] sanei_scsi_open: sanei_scsi_max_request_size=131072 bytes
[sanei_scsi] sanei_scsi_open: SG driver version: 30531
[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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=88ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=103 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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=103 dur=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=4 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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=4 dur=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=42 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=2 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x08
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=2 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=1 dur=1ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=1 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=1 dur=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=1 dur=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=1 dur=1ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=1 dur=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=2 dur=0ms sgat=0 op=0x0a
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=2 dur=0ms sgat=0 op=0x0a
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=4 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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=4 dur=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_scsi] scsi_req_enter: entered 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=42 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 0x8053eb0
[sanei_scsi] sanei_scsi.issue: 0x8053eb0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg2 scsi2 chan=0 id=5 lun=0   em=0 sg_tablesize=128 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=42 dur=0ms sgat=0 op=0x08
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[sanei_debug] Setting debug level of sanei_scsi to 255.
[dll] init: backend `epson' is version 1.0.246
[dll] load: searching backend `canon' in `/usr/local/lib/sane'
[dll] load: trying to load `/usr/local/lib/sane/libsane-canon.so.1'
[dll] load: dlopen()ing `/usr/local/lib/sane/libsane-canon.so.1'
[dll] init: initializing backend `canon'
[sanei_debug] Setting debug level of canon to 128.
[canon] >> sane_init
[canon] sane_init: sane-backends 1.0.18
[canon] >> attach_one
[canon] >> attach
[canon] attach: opening /dev/sg1
[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=Scanner
	bus=2 chan=0 id=1 lun=0  num=1
[sanei_scsi] lx_chk_id: 2,2  0,0  1,1  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg1
[sanei_scsi] get_max_buffer_size for /dev/sg1: 131072
[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=2
[sanei_scsi] lx_chk_id: 2,2  0,0  5,5  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg2
[sanei_scsi] get_max_buffer_size for /dev/sg2: 131072
[sanei_scsi] sanei_scsi_open: sanei_scsi_max_request_size=131072 bytes
[sanei_scsi] sanei_scsi_open: SG driver version: 30531
[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
[canon] attach: sending (standard) INQUIRY
[canon] >> inquiry
[sanei_scsi] scsi_req_enter: entered 0x80741d8
[sanei_scsi] sanei_scsi.issue: 0x80741d8
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg1 scsi2 chan=0 id=1 lun=0   em=0 sg_tablesize=128 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 0x80741d8
[sanei_scsi] sanei_scsi.issue: 0x80741d8
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg1 scsi2 chan=0 id=1 lun=0   em=0 sg_tablesize=128 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
[canon] << inquiry
[canon] attach: sending TEST_UNIT_READY
[canon] >> test_unit_ready
[sanei_scsi] scsi_req_enter: entered 0x80741d8
[sanei_scsi] sanei_scsi.issue: 0x80741d8
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg1 scsi2 chan=0 id=1 lun=0   em=0 sg_tablesize=128 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
     rcv: id=1 blen=0 dur=2ms sgat=0 op=0x00
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x80741d8
[sanei_scsi] sanei_scsi.issue: 0x80741d8
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg1 scsi2 chan=0 id=1 lun=0   em=0 sg_tablesize=128 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
     rcv: id=1 blen=0 dur=2ms sgat=0 op=0x00
[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 44 00 00 00 00 06 00 00 00 00 60 00 00 00
[sanei_scsi] target status: 02 host status: 0000 driver status: 0008
[canon] >> sense_handler
[canon] canon_sense_handler(3, 0x8074230, (nil))
[canon] sense buffer: f0 00 44 00 00 00 00 06 00 00 00 00 60 00 00 00
[canon] sense message: problem not analyzed (unknown SCSI class)
[canon] << sense_handler
[canon] << test_unit_ready
[canon] attach: test unit ready failed (Error during device I/O)
[canon] << attach_one
[canon] << sane_init
[dll] init: backend `canon' is version 1.0.0
[canon] >> sane_get_devices
[canon] << sane_get_devices
[dll] sane_get_devices: found 2 devices
[dll] sane_exit: exiting
[dll] sane_exit: calling backend `v4l's exit function
[dll] sane_exit: calling backend `epson's exit function
[dll] sane_exit: calling backend `canon's exit function
[canon] >> sane_exit
[canon] << sane_exit
[dll] sane_exit: finished
-------------- next part --------------
[sanei_debug] Setting debug level of dll to 4.
[dll] sane_init: SANE dll backend version 1.0.11 from sane-backends 1.0.17
[dll] add_backend: adding backend `net'
[dll] add_backend: adding backend `epson'
[dll] add_backend: adding backend `v4l'
[dll] sane_open: trying to open `canon:/dev/sg1'
[dll] add_backend: adding backend `canon'
[dll] load: searching backend `canon' in `/usr/lib/sane'
[dll] load: trying to load `/usr/lib/sane/libsane-canon.so.1'
[dll] load: dlopen()ing `/usr/lib/sane/libsane-canon.so.1'
[dll] init: initializing backend `canon'
[sanei_debug] Setting debug level of canon to 128.
[canon] >> sane_init
[canon] sane_init: sane-backends 1.0.17
[canon] >> attach_one
[canon] >> attach
[canon] attach: opening /dev/sg1 CANON
[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=Scanner
	bus=2 chan=0 id=1 lun=0  num=1
[sanei_scsi] lx_chk_id: 2,2  0,0  1,1  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg1
[sanei_scsi] get_max_buffer_size for /dev/sg1: 131072
[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=2
[sanei_scsi] lx_chk_id: 2,2  0,0  5,5  0,0
[sanei_scsi] lx_chk_devicename: matched device(direct): /dev/sg2
[sanei_scsi] get_max_buffer_size for /dev/sg2: 131072
[sanei_scsi] sanei_scsi_open: sanei_scsi_max_request_size=131072 bytes
[sanei_scsi] sanei_scsi_open: open of `/dev/sg1 CANON' failed: No such file or directory
[canon] attach: open failed: Invalid argument
[canon] << attach_one
[canon] << sane_init
[dll] init: backend `canon' is version 1.0.0
[canon] >> sane_open
[canon] >> attach
[canon] attach: opening /dev/sg1
[sanei_debug] Setting debug level of sanei_scsi to 255.
[sanei_scsi] sanei_scsi_open: SG driver version: 30531
[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
[canon] attach: sending (standard) INQUIRY
[canon] >> inquiry
[sanei_scsi] scsi_req_enter: entered 0x80536d0
[sanei_scsi] sanei_scsi.issue: 0x80536d0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg1 scsi2 chan=0 id=1 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x12
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x80536d0
[sanei_scsi] sanei_scsi.issue: 0x80536d0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg1 scsi2 chan=0 id=1 lun=0   em=0 sg_tablesize=128 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=0ms sgat=0 op=0x12
[sanei_scsi] sanei_scsi_req_wait: read 64 bytes
[canon] << inquiry
[canon] attach: sending TEST_UNIT_READY
[canon] >> test_unit_ready
[sanei_scsi] scsi_req_enter: entered 0x80536d0
[sanei_scsi] sanei_scsi.issue: 0x80536d0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg1 scsi2 chan=0 id=1 lun=0   em=0 sg_tablesize=128 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
     rcv: id=1 blen=0 dur=2ms sgat=0 op=0x00
[sanei_scsi] scsi_req_enter: queue_used: 1, queue_max: 2
[sanei_scsi] sanei_scsi_req_wait: waiting for 0x80536d0
[sanei_scsi] sanei_scsi.issue: 0x80536d0
dev_max(currently)=32 max_active_device=5 (origin 1)
 def_reserved_size=32768
 >>> device=sg1 scsi2 chan=0 id=1 lun=0   em=0 sg_tablesize=128 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
     rcv: id=1 blen=0 dur=2ms sgat=0 op=0x00
[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 44 00 00 00 00 06 00 00 00 00 60 00 00 00
[sanei_scsi] target status: 02 host status: 0000 driver status: 0008
[canon] >> sense_handler
[canon] canon_sense_handler(3, 0x8053728, (nil))
[canon] sense buffer: f0 00 44 00 00 00 00 06 00 00 00 00 60 00 00 00
[canon] sense message: problem not analyzed (unknown SCSI class)
[canon] << sense_handler
[canon] << test_unit_ready
[canon] attach: test unit ready failed (Error during device I/O)
scanimage: open of device canon:/dev/sg1 failed: Error during device I/O
[dll] sane_exit: exiting
[dll] sane_exit: calling backend `canon's exit function
[canon] >> sane_exit
[canon] << sane_exit
[dll] sane_exit: finished