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: jsm...@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
From gust...@familia.torres.nom.br  Sun Aug 13 23:13:23 2006
From: gust...@familia.torres.nom.br (Gustavo Torres)
Date: Wed Aug 16 15:47:05 2006
Subject: [sane-devel] Backend for MS fingerprint scanners
In-Reply-To: <1155500785.5599.5.ca...@gk-sem3.gkall.org>
References: <1155163044.2158.6.camel@torres>
        <1155500785.5599.5.ca...@gk-sem3.gkall.org>
Message-ID: <1155510803.2295.9.camel@torres>

Hello Gerard,
Thank you for your reply.
Can I please use your code for my project?
I'd be a great start.
Regards,

Gustavo.
Em Dom, 2006-08-13 ?s 22:26 +0200, Gerard Klaver escreveu:
> On Wed, 2006-08-09 at 19:37 -0300, Gustavo Torres wrote:
> > Hello,
> > I'm looking for a Microsoft fingerprint scanner (aka DigitalPersona
> > UareU 4000B) backend, but I couldn't find it.
> > If someone is aware of any effort to implement it, please let me know.
> > I'm considering developing one.
> > Regards,
> > 
> > -- 
> For information, not for your type, but for the Authentec AES2501 i am
> developing a authentec fingerprint scanner backend.
> 
> See: http://gkall.hobby.nl/authentec.html
> 
> No working backend yet, still some init problems.
-- 
Gustavo Torres <gust...@familia.torres.nom.br>
Fam?lia Torres
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 189 bytes
Desc: Esta =?ISO-8859-1?Q?=E9?= uma parte de mensagem
        assinada digitalmente
Url : 
http://lists.alioth.debian.org/pipermail/sane-devel/attachments/20060813/4d74ae9d/attachment.pgp
From adeur...@gmx.net  Wed Aug 16 16:58:16 2006
From: adeur...@gmx.net (abel deuring)
Date: Wed Aug 16 16:53:14 2006
Subject: [sane-devel] Help with Canon 2700F
In-Reply-To: <200608161123.57150.l...@willerding.net>
References: <44e10056.60...@uni-koeln.de>       
<pine.lnx.4.64.0608151443370.9...@wotan.suse.de>
        <200608161123.57150.l...@willerding.net>
Message-ID: <44e34ea8.5030...@gmx.net>

Lutz wrote:

> to me, it looks like that the (working) Epson scanner gives much more info 
> than the canon does.

right: the Epson seems to be OK, while something is broken with the
FS2700, see below.

> 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.

The null argument in the sense handler is OK. Regarding debugging:
gdb is your friend ;) Besides, you can grep for strings like
"attach: sending TEST_UNIT_READY" in the sources files of the
backend; you should be able to quickly find the correct lines; here
you can add for example additional DBG calls.

> [canon] >> sane_init
[...]
> [canon] >> inquiry

... the first SCSI command is sent to the scanner; the usual
INQUIRY; works fine.

> [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

... and the second command (TEST UNIT READY) fails. The reason is
unknown, mostly, because Canon does not release proper
documentation. Without the docs, it's hard to give a more specific
analysis, what the data in the sense buffer exactly means.

The authors of the backend decided to not let the backend continue
to access the scanner in this case -- a reasonable decision. You
might though try to ignore this specific error and patch the backend
accordingly.

Abel

Reply via email to