[sane-devel] Canon MF4660 test/debug
Steve Hardy
steve at appliedrealtime.co.uk
Sun Jan 18 09:39:26 UTC 2009
Ok, so I've taken the more detailed logs :
$ scanimage -L
device `pixma:04A926B0_4175H0007854' is a CANON Canon imageCLASS MF4690
multi-function peripheral
$ export SANE_DEBUG_PIXMA=10
$ export SANE_DEBUG_SANEI_USB=255
$ export SANE_DEBUG=255
$ export SANE_DEBUG_PIXMA=21
$ scanimage -T 2> /tmp/sane_dbg_log_mf4660_01.txt
As observed before, it appears that the initial writes are working (since
the scanner is getting set to "Scanning Mode" according to the front panel
display), but the reads are all timing out :
[sanei_usb] sanei_usb_write_bulk: trying to write 10 bytes
[sanei_usb] 000 DB 20 00 00 00 00 00 00 00 00 . ........
[sanei_usb] sanei_usb_write_bulk: wanted 10 bytes, wrote 10 bytes
[pixma] OUT T=3.474 len=10
[pixma] 00000000:db 20 00 00 00 00 00 00 00 00
[pixma]
[sanei_usb] sanei_usb_read_bulk: trying to read 2 bytes
[sanei_usb] 000 06 06 ..
[sanei_usb] sanei_usb_read_bulk: wanted 2 bytes, got 2 bytes
[pixma] IN T=3.510 len=2
[pixma] 00000000:06 06
[pixma]
[sanei_usb] sanei_usb_write_bulk: trying to write 21 bytes
[sanei_usb] 000 DD 20 00 00 00 00 00 00 0B 00 01 00 00 00 00 00 .
..............
[sanei_usb] 010 00 00 00 00 FF .....
[sanei_usb] sanei_usb_write_bulk: wanted 21 bytes, wrote 21 bytes
[pixma] OUT T=3.510 len=21
[pixma] 00000000:dd 20 00 00 00 00 00 00 0b 00 01 00 00 00 00 00
[pixma] 00000010:00 00 00 00 ff
[pixma]
[sanei_usb] sanei_usb_read_bulk: trying to read 2 bytes
[sanei_usb] sanei_usb_read_bulk: read returned EOF
[pixma] BUG:Unmapped SANE Status code 5
[pixma] IN T=3.513 len=-9
[pixma] ERROR: ETIMEDOUT
[pixma]
gzipped full log is attached
2009/1/17 Nicolas Martin <nicolas.martin at freesurf.fr>
> [pixma] BUG:Unmapped SANE Status code 5
>
> Something happens during the usb transaction, probably in
> sanei_usb_read_bulk() , here (seems like no data is read from scanner):
>
> if (read_size == 0)
> {
> DBG (3, "sanei_usb_read_bulk: read returned EOF\n");
> *size = 0;
> ==> return SANE_STATUS_EOF;
> }
>
> So let's dig a little bit more.
> Could you run the scanimage command like this:
>
> $ export SANE_DEBUG_SANEI_USB=255
> $ export SANE_DEBUG=255
> $ export SANE_DEBUG_PIXMA=21
> $ scanimage -T 2> /tmp/logfile
>
> zip, and send back the file /tmp/logfile you get.
>
> But I'll also map the error code 5 (SANE_STATUS_EOF) in the backend to
> get a more explicit error message.
>
> Nicolas
>
> Le samedi 17 janvier 2009 à 15:51 +0000, Steve Hardy a écrit :
> > Great, thanks for the quick reply!
> >
> > Here is the output of the scanimage -T
> >
> > $ export SANE_DEBUG_PIXMA=10
> > $ scanimage -T
> > [sanei_debug] Setting debug level of pixma to 10.
> > [pixma] pixma version 0.15.0
> > [pixma] pixma_collect_devices() found Canon imageCLASS MF4690 at
> > libusb:005:004
> > [pixma] sanei_bjnp_find_devices:
> > [pixma] eth0 is IPv4 capable, sending broadcast..
> > [pixma] pixma_find_scanners() found 1 devices
> > [pixma] pixma_collect_devices() found Canon imageCLASS MF4690 at
> > libusb:005:004
> > [pixma] sanei_bjnp_find_devices:
> > [pixma] eth0 is IPv4 capable, sending broadcast..
> > [pixma] pixma_open(): Canon imageCLASS MF4690
> > [pixma] Trying to clear the interrupt buffer...
> > [pixma] no packets in buffer
> > [pixma] Reader task id=12781 (forked)
> > scanimage: scanning image of size 640x877 pixels at 24 bits/pixel
> > scanimage: acquiring RGB frame, 8 bits/sample
> > scanimage: reading one scanline, 1920 bytes... [pixma] Reader task
> > started
> > [pixma]
> > [pixma] pixma_scan(): start
> > [pixma] line_size=1920 image_size=1683840 channels=3 depth=8
> > [pixma] dpi=75x75 offset=(0,0) dimension=640x877
> > [pixma] gamma_table=0x8ade184 source=0
> > [pixma] raw_width = 640
> > [pixma] OUT T=3.565 len=10
> > [pixma] 00000000:f3 20 00 00 00 00 00 00 0c 00
> > [pixma]
> > [pixma] IN T=3.567 len=14
> > [pixma] 00000000:06 06 03 00 00 41 03 00 41 10 00 00 00 68
> > [pixma]
> > [pixma] Current status: paper=0 cal=0 lamp=16
> > [pixma] Current status: paper=0 cal=0 lamp=16
> > [pixma] OUT T=3.568 len=20
> > [pixma] 00000000:cf 60 00 00 00 00 00 00 0a 00 01 00 00 00 00 00
> > [pixma] 00000010:00 00 00 ff
> > [pixma]
> > [pixma] IN T=3.575 len=2
> > [pixma] 00000000:06 06
> > [pixma]
> > [pixma] OUT T=3.575 len=20
> > [pixma] 00000000:cf 60 00 00 00 00 00 00 0a 00 01 00 00 04 00 00
> > [pixma] 00000010:00 00 00 fb
> > [pixma]
> > [pixma] IN T=3.582 len=2
> > [pixma] 00000000:06 06
> > [pixma]
> > [pixma] OUT T=3.598 len=10
> > [pixma] 00000000:db 20 00 00 00 00 00 00 00 00
> > [pixma]
> > [pixma] IN T=3.633 len=2
> > [pixma] 00000000:06 06
> > [pixma]
> > [pixma] OUT T=3.633 len=21
> > [pixma] 00000000:dd 20 00 00 00 00 00 00 0b 00 01 00 00 00 00 00
> > [pixma] 00000010:00 00 00 00 ff
> > [pixma]
> > [pixma] BUG:Unmapped SANE Status code 5
> > [pixma] IN T=3.636 len=-9
> > [pixma] ERROR: ETIMEDOUT
> > [pixma]
> > [pixma] No response yet. Timed out in 8 sec.
> > [pixma] IN T=4.640 len=-9
> > [pixma] ERROR: ETIMEDOUT
> > [pixma]
> > [pixma] No response yet. Timed out in 7 sec.
> > [pixma] IN T=5.645 len=-9
> > [pixma] ERROR: ETIMEDOUT
> > [pixma]
> > [pixma] No response yet. Timed out in 6 sec.
> > [pixma] IN T=6.646 len=-9
> > [pixma] ERROR: ETIMEDOUT
> > [pixma]
> > [pixma] No response yet. Timed out in 5 sec.
> > [pixma] IN T=7.648 len=-9
> > [pixma] ERROR: ETIMEDOUT
> > [pixma]
> > [pixma] No response yet. Timed out in 4 sec.
> > [pixma] IN T=8.652 len=-9
> > [pixma] ERROR: ETIMEDOUT
> > [pixma]
> > [pixma] No response yet. Timed out in 3 sec.
> > [pixma] IN T=9.653 len=-9
> > [pixma] ERROR: ETIMEDOUT
> > [pixma]
> > [pixma] No response yet. Timed out in 2 sec.
> > [pixma] IN T=10.657 len=-9
> > [pixma] ERROR: ETIMEDOUT
> > [pixma]
> > [pixma] No response yet. Timed out in 1 sec.
> > [pixma] WARNING:Error in response phase. cmd:404a
> > [pixma] If the scanner hangs, reset it and/or unplug the USB cable.
> > [pixma] OUT T=10.657 len=10
> > [pixma] 00000000:ef 20 00 00 00 00 00 00 00 00
> > [pixma]
> > [pixma] IN T=10.663 len=2
> > [pixma] 00000000:06 06
> > [pixma]
> > [pixma] OUT T=10.663 len=10
> > [pixma] 00000000:f3 20 00 00 00 00 00 00 0c 00
> > [pixma]
> > [pixma] IN T=10.667 len=14
> > [pixma] 00000000:06 06 03 01 00 41 03 00 41 10 00 01 00 66
> > [pixma]
> > [pixma] Current status: paper=1 cal=0 lamp=16
> > [pixma] Current status: paper=1 cal=0 lamp=16
> > [pixma] OUT T=10.668 len=10
> > [pixma] 00000000:f3 20 00 00 00 00 00 00 0c 00
> > [pixma]
> > [pixma] IN T=10.670 len=14
> > [pixma] 00000000:06 06 03 01 00 41 03 00 41 10 00 01 00 66
> > [pixma]
> > [pixma] Current status: paper=1 cal=0 lamp=16
> > [pixma] Current status: paper=1 cal=0 lamp=16
> > [pixma] OUT T=10.670 len=20
> > [pixma] 00000000:cf 60 00 00 00 00 00 00 0a 00 01 00 00 00 00 00
> > [pixma] 00000010:00 00 00 ff
> > [pixma]
> > [pixma] IN T=10.674 len=2
> > [pixma] 00000000:06 06
> > [pixma]
> > [pixma] OUT T=10.674 len=10
> > [pixma] 00000000:f3 20 00 00 00 00 00 00 0c 00
> > [pixma]
> > [pixma] IN T=10.677 len=14
> > [pixma] 00000000:06 06 03 01 00 41 03 00 41 10 00 01 00 66
> > [pixma]
> > [pixma] Current status: paper=1 cal=0 lamp=16
> > [pixma] Current status: paper=1 cal=0 lamp=16
> > [pixma] pixma_scan() failed ETIMEDOUT
> > [pixma] Reader task terminated: ETIMEDOUT
> > [pixma] read_image():reader task closed the pipe:0 bytes received,
> > 1683840 bytes expected
> > FAIL Error: Error during device I/O
> > [pixma] pixma_close(): Canon imageCLASS MF4690
> > $
> >
> > 2009/1/17 Nicolas Martin <nicolas.martin at freesurf.fr>
> > This Multifunction scanner should be candidate to be handled
> > by the
> > pixma backend, where it is declared.
> >
> > In order to check what's going on, could you run scanimage as
> > follows:
> >
> > $ export SANE_DEBUG_PIXMA=10
> > $ scanimage -T
> >
> > and send back the content of the console.
> >
> > Nicolas
> >
> >
>
>
--
Steve Hardy
Applied Realtime Systems Limited
www.appliedrealtime.co.uk
Tel : 0845 8678251
Fax : 0845 8678241
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.alioth.debian.org/pipermail/sane-devel/attachments/20090118/4fa47d78/attachment-0001.htm
-------------- next part --------------
A non-text attachment was scrubbed...
Name: sane_dbg_log_mf4660_01.txt.gz
Type: application/x-gzip
Size: 6548 bytes
Desc: not available
Url : http://lists.alioth.debian.org/pipermail/sane-devel/attachments/20090118/4fa47d78/attachment-0001.bin
More information about the sane-devel
mailing list