[sane-devel] Canon MF4660 test/debug
Nicolas Martin
nicolas.martin at freesurf.fr
Sun Jan 18 10:25:52 UTC 2009
Thanks for this log, will review it with Dennis Lou, who wrote the
ImageClass part of the pixma backend, and will keep you posted.
Seems like it is always failing on the select_source message, maybe the
scanner does not expect it as is, and this may require to get a Windows
snoop trace (using SnoopyPro, this is very easy to do), to check if some
protocol messages are different for this MFP.
Nicolas
Le dimanche 18 janvier 2009 à 09:39 +0000, Steve Hardy a écrit :
> 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
More information about the sane-devel
mailing list