[sane-devel] Canon MF4660 test/debug

[Nicolas Martin]

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