[sane-devel] MP600R doesn't work

Frode V. Fjeld frodevf at gmail.com
Mon Sep 3 22:05:39 UTC 2012


Louis Lagendijk <louis at fazant.net> writes:

> On Thu, 2012-08-30 at 10:23 +0200, Frode V. Fjeld wrote:
>> Louis Lagendijk <louis at fazant.net> writes:
>> 
>> > I will have a look at this when I get some time. I have seen this once
>> > on my own scanner, but thought that the latest git version fixed it.
>> > This could be a timing issue that shows up on wlan only (I use a wired
>> > network connection).  Just to be sure: did you power off/on the
>> > scanner?  It looks as if the protocol stack in Canon devices get
>> > confused sometines.....
>
> Can you please make a debug trace at level 14 or higher. There may be
> an issue with the scanner lookup. This will show if a match on the
> model can be found. You may want to checkoup the latest git, as I made
> the error that is reported somewhat clearer.

Hi, here's a trace from the latest git version, against a freshly booted
scanner. I didn't really notice any different behavior, unfortunately. I
was going to try with a wired connection too, but apparently
reconfiguring the LAN settings on this thing is semi-impossible. (Do I
really need a windows machine and USB connection to do it??)

[sanei_debug] Setting debug level of pixma to 14.
[pixma] pixma is compiled without pthread support.
[pixma] pixma version 0.17.0
[pixma] sanei_bjnp_find_devices:
[pixma] No devices specified in configuration file.
[pixma] Added all configured scanners, now do auto detection...
[pixma] lo is not a valid IPv4 interface, skipping...
[pixma] wlan0 is IPv4 capable, sending broadcast, socket = 3
[pixma] lo is not a valid IPv6 interface, skipping...
[pixma] wlan0 is IPv6 capable, sending broadcast, socket = 4
[pixma] Select returned, time left 0.457044....
[pixma] Discover response:
[pixma]  00000000:42 4a 4e 50 82 01 00 00  00 00 00 00 00 00 00 10 
[pixma]  00000010:00 01 08 00 06 04 00 00  85 84 48 64 c0 a8 01 26 
[pixma] Forward lookup for printer succeeded, using as hostname
[pixma] bjnp_allocate_device(bjnp://printer:8612) 0
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Discover response:
[pixma]  00000000:42 4a 4e 50 82 01 00 00  00 00 00 00 00 00 00 10 
[pixma]  00000010:00 01 08 00 06 04 00 00  85 84 48 64 c0 a8 01 26 
[pixma] Scanner not yet in our list, added it: printer:8612
[pixma] Get scanner identity
[pixma]  00000000:42 4a 4e 50 02 30 00 00  00 01 00 00 00 00 00 00 
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] scanner identity:
[pixma]  00000000:42 4a 4e 50 82 30 00 00  00 01 00 00 00 00 00 44 
[pixma]  00000010:00 44 4d 46 47 3a 43 41  4e 4f 4e 3b 43 4d 44 3a 
[pixma]  00000020:4d 75 6c 74 69 50 41 53  53 20 32 2e 31 3b 4d 44 
[pixma]  00000030:4c 3a 4d 50 36 30 30 52  3b 43 4c 53 3a 49 4d 41 
[pixma]  00000040:47 45 3b 44 45 53 3a 43  41 4e 4f 4e 20 4d 50 36 
[pixma]  00000050:30 30 52 3b 
[pixma] Scanner identity string = MFG:CANON;CMD:MultiPASS 2.1;MDL:MP600R;CLS:IMAGE;DES:CANON MP600R; - lenght = 66
[pixma] Scanner model = CANON MP600R
[pixma] Name MP150(Canon PIXMA MP150) does not match CANON MP600R
[pixma] Name MP170(Canon PIXMA MP170) does not match CANON MP600R
[pixma] Name MP450(Canon PIXMA MP450) does not match CANON MP600R
[pixma] Name MP500(Canon PIXMA MP500) does not match CANON MP600R
[pixma] Name MP530(Canon PIXMA MP530) does not match CANON MP600R
[pixma] Name MP800(Canon PIXMA MP800) does not match CANON MP600R
[pixma] Name MP800R(Canon PIXMA MP800R) does not match CANON MP600R
[pixma] Name MP830(Canon PIXMA MP830) does not match CANON MP600R
[pixma] Name MP140(Canon PIXMA MP140) does not match CANON MP600R
[pixma] Name MP160(Canon PIXMA MP160) does not match CANON MP600R
[pixma] Name MP180(Canon PIXMA MP180) does not match CANON MP600R
[pixma] Name MP460(Canon PIXMA MP460) does not match CANON MP600R
[pixma] Name MP510(Canon PIXMA MP510) does not match CANON MP600R
[pixma] Name MP600(Canon PIXMA MP600) does not match CANON MP600R
[pixma] Scanner model found: Name MP600R(Canon PIXMA MP600R) matches CANON MP600R
[pixma] Select returned, time left 0.499992....
[pixma] Discover response:
[pixma]  00000000:42 4a 4e 50 82 01 00 00  00 00 00 00 00 00 00 10 
[pixma]  00000010:00 01 08 00 06 04 00 00  85 84 48 64 c0 a8 01 26 
[pixma] Forward lookup for printer succeeded, using as hostname
[pixma] bjnp_allocate_device(bjnp://printer:8612) 1
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Discover response:
[pixma]  00000000:42 4a 4e 50 82 01 00 00  00 00 00 00 00 00 00 10 
[pixma]  00000010:00 01 08 00 06 04 00 00  85 84 48 64 c0 a8 01 26 
[pixma] Scanner at bjnp://printer:8612 was added before, good!
[pixma] scanner discovery finished...
[pixma] pixma_collect_devices() found Canon PIXMA MP600R at bjnp://printer:8612
[pixma] pixma_find_scanners() found 1 devices
[pixma] sanei_bjnp_find_devices:
[pixma] No devices specified in configuration file.
[pixma] Added all configured scanners, now do auto detection...
[pixma] lo is not a valid IPv4 interface, skipping...
[pixma] wlan0 is IPv4 capable, sending broadcast, socket = 3
[pixma] lo is not a valid IPv6 interface, skipping...
[pixma] wlan0 is IPv6 capable, sending broadcast, socket = 4
[pixma] Select returned, time left 0.239019....
[pixma] Discover response:
[pixma]  00000000:42 4a 4e 50 82 01 00 00  00 00 00 00 00 00 00 10 
[pixma]  00000010:00 01 08 00 06 04 00 00  85 84 48 64 c0 a8 01 26 
[pixma] Forward lookup for printer succeeded, using as hostname
[pixma] bjnp_allocate_device(bjnp://printer:8612) 0
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Discover response:
[pixma]  00000000:42 4a 4e 50 82 01 00 00  00 00 00 00 00 00 00 10 
[pixma]  00000010:00 01 08 00 06 04 00 00  85 84 48 64 c0 a8 01 26 
[pixma] Scanner not yet in our list, added it: printer:8612
[pixma] Get scanner identity
[pixma]  00000000:42 4a 4e 50 02 30 00 00  00 01 00 00 00 00 00 00 
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] scanner identity:
[pixma]  00000000:42 4a 4e 50 82 30 00 00  00 01 00 00 00 00 00 44 
[pixma]  00000010:00 44 4d 46 47 3a 43 41  4e 4f 4e 3b 43 4d 44 3a 
[pixma]  00000020:4d 75 6c 74 69 50 41 53  53 20 32 2e 31 3b 4d 44 
[pixma]  00000030:4c 3a 4d 50 36 30 30 52  3b 43 4c 53 3a 49 4d 41 
[pixma]  00000040:47 45 3b 44 45 53 3a 43  41 4e 4f 4e 20 4d 50 36 
[pixma]  00000050:30 30 52 3b 
[pixma] Scanner identity string = MFG:CANON;CMD:MultiPASS 2.1;MDL:MP600R;CLS:IMAGE;DES:CANON MP600R; - lenght = 66
[pixma] Scanner model = CANON MP600R
[pixma] Name MP150(Canon PIXMA MP150) does not match CANON MP600R
[pixma] Name MP170(Canon PIXMA MP170) does not match CANON MP600R
[pixma] Name MP450(Canon PIXMA MP450) does not match CANON MP600R
[pixma] Name MP500(Canon PIXMA MP500) does not match CANON MP600R
[pixma] Name MP530(Canon PIXMA MP530) does not match CANON MP600R
[pixma] Name MP800(Canon PIXMA MP800) does not match CANON MP600R
[pixma] Name MP800R(Canon PIXMA MP800R) does not match CANON MP600R
[pixma] Name MP830(Canon PIXMA MP830) does not match CANON MP600R
[pixma] Name MP140(Canon PIXMA MP140) does not match CANON MP600R
[pixma] Name MP160(Canon PIXMA MP160) does not match CANON MP600R
[pixma] Name MP180(Canon PIXMA MP180) does not match CANON MP600R
[pixma] Name MP460(Canon PIXMA MP460) does not match CANON MP600R
[pixma] Name MP510(Canon PIXMA MP510) does not match CANON MP600R
[pixma] Name MP600(Canon PIXMA MP600) does not match CANON MP600R
[pixma] Scanner model found: Name MP600R(Canon PIXMA MP600R) matches CANON MP600R
[pixma] Select returned, time left 0.499992....
[pixma] Discover response:
[pixma]  00000000:42 4a 4e 50 82 01 00 00  00 00 00 00 00 00 00 10 
[pixma]  00000010:00 01 08 00 06 04 00 00  85 84 48 64 c0 a8 01 26 
[pixma] Forward lookup for printer succeeded, using as hostname
[pixma] bjnp_allocate_device(bjnp://printer:8612) 1
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Discover response:
[pixma]  00000000:42 4a 4e 50 82 01 00 00  00 00 00 00 00 00 00 10 
[pixma]  00000010:00 01 08 00 06 04 00 00  85 84 48 64 c0 a8 01 26 
[pixma] Scanner at bjnp://printer:8612 was added before, good!
[pixma] scanner discovery finished...
[pixma] pixma_collect_devices() found Canon PIXMA MP600R at bjnp://printer:8612
[pixma] pixma_open(): Canon PIXMA MP600R
[pixma] sanei_bjnp_open(bjnp://printer:8612, 0):
[pixma] bjnp_allocate_device(bjnp://printer:8612) 1
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Discover response:
[pixma]  00000000:42 4a 4e 50 82 01 00 00  00 00 00 00 00 00 00 10 
[pixma]  00000010:00 01 08 00 06 04 00 00  85 84 48 64 c0 a8 01 26 
[pixma] sanei_bjnp_activate (0)
[pixma] Job details
[pixma]  00000000:42 4a 4e 50 02 10 00 00  00 02 00 00 00 00 01 88 
[pixma]  00000010:00 00 00 00 00 00 00 00  00 4b 00 61 00 72 00 6f 
[pixma]  00000020:00 6e 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000030:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000040:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000050:00 00 00 00 00 00 00 00  00 66 00 72 00 6f 00 64 
[pixma]  00000060:00 65 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000070:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000080:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000090:00 00 00 00 00 00 00 00  00 50 00 72 00 6f 00 63 
[pixma]  000000a0:00 65 00 73 00 73 00 20  00 49 00 44 00 20 00 3d 
[pixma]  000000b0:00 20 00 32 00 33 00 31  00 35 00 00 00 00 00 00 
[pixma]  000000c0:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  000000d0:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  000000e0:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  000000f0:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000100:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000110:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000120:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000130:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000140:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000150:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000160:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000170:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000180:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000190:00 00 00 00 00 00 00 00  c0 2e 03 02 00 00 00 00 
[pixma]  000001a0:2f 00 00 00 00 00 00 00  
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Job details response:
[pixma]  00000000:42 4a 4e 50 82 10 00 00  00 02 00 01 00 00 00 04 
[pixma]  00000010:01 41 bb 48 
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_write_bulk(0, bufferptr, 0x10 = 16)
[pixma] bjnp_write: sending 0x10 = 16 bytes
[pixma]  00000000:42 4a 4e 50 02 21 00 00  00 03 00 01 00 00 00 10 
[pixma]  00000010:f3 20 00 00 00 00 00 00  00 00 00 00 00 00 00 10 
[pixma] bjnp_recv_header: receiving response header
[pixma] TCP response header(scanner data = 4 bytes):
[pixma]  00000000:42 4a 4e 50 82 21 00 00  00 03 00 01 00 00 00 04 
[pixma] bjnp_recv_data: receiving response data
[pixma] bjnp_recv_data: read response payload (4 bytes max)
[pixma] Received TCP response payload (4 bytes):
[pixma]  00000000:00 00 00 10 
[pixma] OUT  T=2.448 len=16
[pixma]  00000000:f3 20 00 00 00 00 00 00  00 00 00 00 00 00 00 10 
[pixma] 
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_read_bulk(0, bufferptr, 0x18 = 140734558481272)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start, Short block = 0 blocksize = 0x400 = 1024
[pixma] So far received 0x0 bytes = 0, need 0x18 = 24
[pixma] No (more) scanner data available, requesting more
[pixma] bjnp_send_read_req sending command
[pixma]  00000000:42 4a 4e 50 02 20 00 00  00 04 00 01 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] TCP response header(scanner data = 0 bytes):
[pixma]  00000000:42 4a 4e 50 82 20 83 00  00 04 00 01 00 00 00 00 
[pixma] Scanner reports 0x0 = 0 bytes available
[pixma] reading 0x0 = 0 (of max 0x18 = 24) bytes more
[pixma] bjnp_recv_data: receiving response data
[pixma] bjnp_recv_data: read response payload (24 bytes max)
[pixma] bjnp_recv_data: could not read response payload (select timed out): Success!
[pixma] IN   T=6.460 len=-9
[pixma]   ERROR: ETIMEDOUT
[pixma] 
[pixma] No response yet. Timed out in 8 sec.
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_read_bulk(0, bufferptr, 0x18 = 140734558481272)
[pixma] Scanner has no more data available, return immediately!
[pixma] IN   T=6.460 len=-14
[pixma]   ERROR: EEOF
[pixma] 
[pixma] WARNING: Error in response phase. cmd:f320
[pixma]   If the scanner hangs, reset it and/or unplug the USB cable.
[pixma] bjnp_set_timeout to 200
[pixma] bjnp_read_int(0, bufferptr, 0x10 = 16):
[pixma] Poll details (type 0)
[pixma]  00000000:42 4a 4e 50 02 32 00 00  00 05 00 00 00 00 00 50 
[pixma]  00000010:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000020:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000030:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000040:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000050:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Poll details response:
[pixma]  00000000:42 4a 4e 50 82 32 00 00  00 05 00 00 00 00 00 24 
[pixma]  00000010:00 00 00 00 00 00 00 00  00 00 00 14 00 00 00 00 
[pixma]  00000020:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000030:00 00 00 00 
[pixma] Poll details (type 1)
[pixma]  00000000:42 4a 4e 50 02 32 00 00  00 06 00 00 00 00 00 50 
[pixma]  00000010:00 01 00 00 00 00 00 00  00 66 00 72 00 6f 00 64 
[pixma]  00000020:00 65 00 20 00 20 00 4b  00 61 00 72 00 6f 00 6e 
[pixma]  00000030:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000040:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000050:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Poll details response:
[pixma]  00000000:42 4a 4e 50 82 32 00 00  00 06 00 00 00 00 00 24 
[pixma]  00000010:00 00 00 00 00 00 00 01  00 00 00 14 00 00 00 00 
[pixma]  00000020:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000030:00 00 00 00 
[pixma] Poll details (type 2)
[pixma]  00000000:42 4a 4e 50 02 32 00 00  00 07 00 00 00 00 00 74 
[pixma]  00000010:00 02 00 00 00 00 00 01  00 66 00 72 00 6f 00 64 
[pixma]  00000020:00 65 00 20 00 20 00 4b  00 61 00 72 00 6f 00 6e 
[pixma]  00000030:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000040:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000050:00 00 00 00 00 00 00 00  00 00 00 14 00 00 00 00 
[pixma]  00000060:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000070:00 00 00 10 32 30 31 32  30 39 30 33 32 30 33 34 
[pixma]  00000080:32 33 00 00 
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Poll details response:
[pixma]  00000000:42 4a 4e 50 82 32 00 00  00 07 00 00 00 00 00 24 
[pixma]  00000010:00 00 00 00 00 00 00 01  00 00 00 14 00 00 00 00 
[pixma]  00000020:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000030:00 00 00 00 
[pixma] sanei_bjnp_deactivate (0)
[pixma] Finish scanjob
[pixma]  00000000:42 4a 4e 50 02 11 00 00  00 08 00 01 00 00 00 00 
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Finish scanjob response
[pixma]  00000000:42 4a 4e 50 82 11 00 00  00 08 00 01 00 00 00 00 
[pixma] Reader task id=2318 (forked)
scanimage: scanning image of size 638x877 pixels at 24 bits/pixel
scanimage: acquiring RGB frame, 8 bits/sample
scanimage: reading one scanline, 1914 bytes...	[pixma] Reader task started
[pixma] sanei_bjnp_activate (0)
[pixma] Job details
[pixma]  00000000:42 4a 4e 50 02 10 00 00  00 09 00 01 00 00 01 88 
[pixma]  00000010:00 00 00 00 00 00 00 00  00 4b 00 61 00 72 00 6f 
[pixma]  00000020:00 6e 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000030:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000040:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000050:00 00 00 00 00 00 00 00  00 66 00 72 00 6f 00 64 
[pixma]  00000060:00 65 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000070:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000080:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000090:00 00 00 00 00 00 00 00  00 50 00 72 00 6f 00 63 
[pixma]  000000a0:00 65 00 73 00 73 00 20  00 49 00 44 00 20 00 3d 
[pixma]  000000b0:00 20 00 32 00 33 00 31  00 38 00 00 00 00 00 00 
[pixma]  000000c0:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  000000d0:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  000000e0:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  000000f0:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000100:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000110:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000120:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000130:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000140:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000150:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000160:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000170:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000180:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000190:00 00 00 00 00 00 00 00  80 61 2f 6d e0 7f 00 00 
[pixma]  000001a0:00 00 00 00 00 00 00 00  
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Job details response:
[pixma]  00000000:42 4a 4e 50 82 10 00 00  00 09 00 02 00 00 00 04 
[pixma]  00000010:01 7f 87 98 
[pixma] 
[pixma] pixma_scan(): start
[pixma]   line_size=1914 image_size=1678578 channels=3 depth=8
[pixma]   dpi=75x75 offset=(0,0) dimension=638x877
[pixma]   gamma_table=0x202c8f8 source=0
[pixma]   threshold=127 threshold_curve=0
[pixma] bjnp_set_timeout to 100
[pixma] bjnp_read_int(0, bufferptr, 0x10 = 16):
[pixma] Poll details (type 2)
[pixma]  00000000:42 4a 4e 50 02 32 00 00  00 0a 00 00 00 00 00 74 
[pixma]  00000010:00 02 00 00 00 00 00 01  00 66 00 72 00 6f 00 64 
[pixma]  00000020:00 65 00 20 00 20 00 4b  00 61 00 72 00 6f 00 6e 
[pixma]  00000030:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000040:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000050:00 00 00 00 00 00 00 00  00 00 00 14 00 00 00 00 
[pixma]  00000060:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000070:00 00 00 10 32 30 31 32  30 39 30 33 32 30 33 34 
[pixma]  00000080:32 33 00 00 
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Poll details response:
[pixma]  00000000:42 4a 4e 50 82 32 00 00  00 0a 00 00 00 00 00 24 
[pixma]  00000010:00 00 00 00 00 00 00 01  00 00 00 14 00 00 00 00 
[pixma]  00000020:00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]  00000030:00 00 00 00 
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_write_bulk(0, bufferptr, 0x10 = 16)
[pixma] bjnp_write: sending 0x10 = 16 bytes
[pixma]  00000000:42 4a 4e 50 02 21 00 00  00 0b 00 02 00 00 00 10 
[pixma]  00000010:db 20 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] TCP response header(scanner data = 4 bytes):
[pixma]  00000000:42 4a 4e 50 82 21 00 00  00 0b 00 02 00 00 00 04 
[pixma] bjnp_recv_data: receiving response data
[pixma] bjnp_recv_data: read response payload (4 bytes max)
[pixma] Received TCP response payload (4 bytes):
[pixma]  00000000:00 00 00 10 
[pixma] OUT  T=6.508 len=16
[pixma]  00000000:db 20 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma] 
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_read_bulk(0, bufferptr, 0x8 = 140734558481080)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start, Short block = 0 blocksize = 0x400 = 1024
[pixma] So far received 0x0 bytes = 0, need 0x8 = 8
[pixma] No (more) scanner data available, requesting more
[pixma] bjnp_send_read_req sending command
[pixma]  00000000:42 4a 4e 50 02 20 00 00  00 0c 00 02 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] bjnp_recv_header: could not read response header (select timed out): Success!
[pixma] IN   T=10.511 len=-9
[pixma]   ERROR: ETIMEDOUT
[pixma] 
[pixma] No response yet. Timed out in 8 sec.
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_read_bulk(0, bufferptr, 0x8 = 140734558481080)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start, Short block = 0 blocksize = 0x400 = 1024
[pixma] So far received 0x0 bytes = 0, need 0x8 = 8
[pixma] No (more) scanner data available, requesting more
[pixma] bjnp_send_read_req sending command
[pixma]  00000000:42 4a 4e 50 02 20 00 00  00 0d 00 02 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] bjnp_recv_header: could not read response header (select timed out): Success!
[pixma] IN   T=14.515 len=-9
[pixma]   ERROR: ETIMEDOUT
[pixma] 
[pixma] No response yet. Timed out in 7 sec.
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_read_bulk(0, bufferptr, 0x8 = 140734558481080)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start, Short block = 0 blocksize = 0x400 = 1024
[pixma] So far received 0x0 bytes = 0, need 0x8 = 8
[pixma] No (more) scanner data available, requesting more
[pixma] bjnp_send_read_req sending command
[pixma]  00000000:42 4a 4e 50 02 20 00 00  00 0e 00 02 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] bjnp_recv_header: could not read response header (select timed out): Success!
[pixma] IN   T=18.518 len=-9
[pixma]   ERROR: ETIMEDOUT
[pixma] 
[pixma] No response yet. Timed out in 6 sec.
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_read_bulk(0, bufferptr, 0x8 = 140734558481080)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start, Short block = 0 blocksize = 0x400 = 1024
[pixma] So far received 0x0 bytes = 0, need 0x8 = 8
[pixma] No (more) scanner data available, requesting more
[pixma] bjnp_send_read_req sending command
[pixma]  00000000:42 4a 4e 50 02 20 00 00  00 0f 00 02 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] bjnp_recv_header: could not read response header (select timed out): Success!
[pixma] IN   T=22.521 len=-9
[pixma]   ERROR: ETIMEDOUT
[pixma] 
[pixma] No response yet. Timed out in 5 sec.
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_read_bulk(0, bufferptr, 0x8 = 140734558481080)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start, Short block = 0 blocksize = 0x400 = 1024
[pixma] So far received 0x0 bytes = 0, need 0x8 = 8
[pixma] No (more) scanner data available, requesting more
[pixma] bjnp_send_read_req sending command
[pixma]  00000000:42 4a 4e 50 02 20 00 00  00 10 00 02 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] bjnp_recv_header: could not read response header (select timed out): Success!
[pixma] IN   T=26.522 len=-9
[pixma]   ERROR: ETIMEDOUT
[pixma] 
[pixma] No response yet. Timed out in 4 sec.
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_read_bulk(0, bufferptr, 0x8 = 140734558481080)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start, Short block = 0 blocksize = 0x400 = 1024
[pixma] So far received 0x0 bytes = 0, need 0x8 = 8
[pixma] No (more) scanner data available, requesting more
[pixma] bjnp_send_read_req sending command
[pixma]  00000000:42 4a 4e 50 02 20 00 00  00 11 00 02 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] bjnp_recv_header: could not read response header (select timed out): Success!
[pixma] IN   T=30.523 len=-9
[pixma]   ERROR: ETIMEDOUT
[pixma] 
[pixma] No response yet. Timed out in 3 sec.
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_read_bulk(0, bufferptr, 0x8 = 140734558481080)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start, Short block = 0 blocksize = 0x400 = 1024
[pixma] So far received 0x0 bytes = 0, need 0x8 = 8
[pixma] No (more) scanner data available, requesting more
[pixma] bjnp_send_read_req sending command
[pixma]  00000000:42 4a 4e 50 02 20 00 00  00 12 00 02 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] bjnp_recv_header: could not read response header (select timed out): Success!
[pixma] IN   T=34.528 len=-9
[pixma]   ERROR: ETIMEDOUT
[pixma] 
[pixma] No response yet. Timed out in 2 sec.
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_read_bulk(0, bufferptr, 0x8 = 140734558481080)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start, Short block = 0 blocksize = 0x400 = 1024
[pixma] So far received 0x0 bytes = 0, need 0x8 = 8
[pixma] No (more) scanner data available, requesting more
[pixma] bjnp_send_read_req sending command
[pixma]  00000000:42 4a 4e 50 02 20 00 00  00 13 00 02 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] bjnp_recv_header: could not read response header (select timed out): Success!
[pixma] IN   T=38.532 len=-9
[pixma]   ERROR: ETIMEDOUT
[pixma] 
[pixma] No response yet. Timed out in 1 sec.
[pixma] WARNING: Error in response phase. cmd:db20
[pixma]   If the scanner hangs, reset it and/or unplug the USB cable.
[pixma] Scanner hangs? Sending abort_session command.
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_read_bulk(0, bufferptr, 0x80000 = 140734558481176)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start, Short block = 0 blocksize = 0x400 = 1024
[pixma] So far received 0x0 bytes = 0, need 0x80000 = 524288
[pixma] No (more) scanner data available, requesting more
[pixma] bjnp_send_read_req sending command
[pixma]  00000000:42 4a 4e 50 02 20 00 00  00 14 00 02 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] bjnp_recv_header: could not read response header (select timed out): Success!
[pixma] IN   T=42.536 len=-9
[pixma]   ERROR: ETIMEDOUT
[pixma] 
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_write_bulk(0, bufferptr, 0x10 = 16)
[pixma] bjnp_write: sending 0x10 = 16 bytes
[pixma]  00000000:42 4a 4e 50 02 21 00 00  00 15 00 02 00 00 00 10 
[pixma]  00000010:ef 20 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] bjnp_recv_header: could not read response header (select timed out): Success!
[pixma] Could not read response to command!
[pixma] OUT  T=46.541 len=-9
[pixma]  00000000:ef 20 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]   ERROR: ETIMEDOUT
[pixma] 
[pixma] bjnp_set_timeout to 20000
[pixma] bjnp_write_bulk(0, bufferptr, 0x10 = 16)
[pixma] bjnp_write: sending 0x10 = 16 bytes
[pixma]  00000000:42 4a 4e 50 02 21 00 00  00 16 00 02 00 00 00 10 
[pixma]  00000010:db 20 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma] bjnp_recv_header: receiving response header
[pixma] bjnp_recv_header: could not read response header (select timed out): Success!
[pixma] Could not read response to command!
[pixma] OUT  T=51.046 len=-9
[pixma]  00000000:db 20 00 00 00 00 00 00  00 00 00 00 00 00 00 00 
[pixma]   ERROR: ETIMEDOUT
[pixma] 
[pixma] pixma_scan() failed ETIMEDOUT
[pixma] sanei_bjnp_deactivate (0)
[pixma] Finish scanjob
[pixma]  00000000:42 4a 4e 50 02 11 00 00  00 17 00 02 00 00 00 00 
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Finish scanjob response
[pixma]  00000000:42 4a 4e 50 82 11 00 00  00 17 00 02 00 00 00 00 
[pixma] Reader task terminated: ETIMEDOUT
[pixma] read_image():reader task closed the pipe:0 bytes received, 1678578 bytes expected
FAIL Error: Error during device I/O
[pixma] pixma_close(): Canon PIXMA MP600R
[pixma] sanei_bjnp_close(0):
[pixma] sanei_bjnp_deactivate (0)
[pixma] Finish scanjob
[pixma]  00000000:42 4a 4e 50 02 11 00 00  00 09 00 01 00 00 00 00 
[pixma] setup_udp_socket: Setting up the UDP socket, dest: 192.168.1.38  port 8612
[pixma] Finish scanjob response
[pixma]  00000000:42 4a 4e 50 82 11 83 00  00 09 00 01 00 00 00 00 


Thanks,
-- 
Frode V. Fjeld




More information about the sane-devel mailing list