[sane-devel] MP600R doesn't work

Frode V. Fjeld frodevf at gmail.com
Tue Sep 11 21:10:15 UTC 2012


Louis Lagendijk <louis at fazant.net> writes:

> This is probably where it goes wrong: these is no data returned to the
> status command sent before (f320). Can you please try what happens if
> your replace the line 2076  
>       break;
>  in pixma_bjnp.c by
> sleep(1);
> continue;

I did this, and also injected a "fprintf(stderr, "FVF\n");" before the
sleep:

[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.423548....
[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 a 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 a 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 = MP600R
[pixma] Scanner model MP150(Canon PIXMA MP150) not found, giving up! MP600R
[pixma] Scanner model MP170(Canon PIXMA MP170) not found, giving up! MP600R
[pixma] Scanner model MP450(Canon PIXMA MP450) not found, giving up! MP600R
[pixma] Scanner model MP500(Canon PIXMA MP500) not found, giving up! MP600R
[pixma] Scanner model MP530(Canon PIXMA MP530) not found, giving up! MP600R
[pixma] Scanner model MP800(Canon PIXMA MP800) not found, giving up! MP600R
[pixma] Scanner model MP800R(Canon PIXMA MP800R) not found, giving up! MP600R
[pixma] Scanner model MP830(Canon PIXMA MP830) not found, giving up! MP600R
[pixma] Scanner model MP140(Canon PIXMA MP140) not found, giving up! MP600R
[pixma] Scanner model MP160(Canon PIXMA MP160) not found, giving up! MP600R
[pixma] Scanner model MP180(Canon PIXMA MP180) not found, giving up! MP600R
[pixma] Scanner model MP460(Canon PIXMA MP460) not found, giving up! MP600R
[pixma] Scanner model MP510(Canon PIXMA MP510) not found, giving up! MP600R
[pixma] Scanner model MP600(Canon PIXMA MP600) not found, giving up! MP600R
[pixma] Scanner model found: Name MP600R(Canon PIXMA MP600R) matches 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 a 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.256606....
[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 a 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 a 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 = MP600R
[pixma] Scanner model MP150(Canon PIXMA MP150) not found, giving up! MP600R
[pixma] Scanner model MP170(Canon PIXMA MP170) not found, giving up! MP600R
[pixma] Scanner model MP450(Canon PIXMA MP450) not found, giving up! MP600R
[pixma] Scanner model MP500(Canon PIXMA MP500) not found, giving up! MP600R
[pixma] Scanner model MP530(Canon PIXMA MP530) not found, giving up! MP600R
[pixma] Scanner model MP800(Canon PIXMA MP800) not found, giving up! MP600R
[pixma] Scanner model MP800R(Canon PIXMA MP800R) not found, giving up! MP600R
[pixma] Scanner model MP830(Canon PIXMA MP830) not found, giving up! MP600R
[pixma] Scanner model MP140(Canon PIXMA MP140) not found, giving up! MP600R
[pixma] Scanner model MP160(Canon PIXMA MP160) not found, giving up! MP600R
[pixma] Scanner model MP180(Canon PIXMA MP180) not found, giving up! MP600R
[pixma] Scanner model MP460(Canon PIXMA MP460) not found, giving up! MP600R
[pixma] Scanner model MP510(Canon PIXMA MP510) not found, giving up! MP600R
[pixma] Scanner model MP600(Canon PIXMA MP600) not found, giving up! MP600R
[pixma] Scanner model found: Name MP600R(Canon PIXMA MP600R) matches 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 a 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 a 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 33 00 31 00 34  00 30 00 38 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 0e 45 02 00 00 00 00 
[pixma]  000001a0:2f 00 00 00 00 00 00 00  
[pixma] setup_udp_socket: Setting up a 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:12 71 fc 20 
[pixma] bjnp_open_tcp: Setting up a TCP socket, dest: 192.168.1.38  port 8612
[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.477 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 = 24)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start
[pixma] Received 0x0 = 0 bytes, backend requested 0x18 = 24 bytes
[pixma] No (more) scanner data available, requesting more( blocksize = 2048 =800
[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 
FVF
[pixma] returning 0 bytes, backend expexts 24
[pixma] IN   T=3.485 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 a 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 a 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 31 31 32 33 30 34 
[pixma]  00000080:34 32 00 00 
[pixma] setup_udp_socket: Setting up a 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 a 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=31409 (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 33 00 31 00 34  00 30 00 39 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  00 00 00 00 00 00 00 00 
[pixma]  000001a0:80 51 b8 33 56 7f 00 00  
[pixma] setup_udp_socket: Setting up a 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:12 82 15 38 
[pixma] bjnp_open_tcp: Setting up a TCP socket, dest: 192.168.1.38  port 8612
[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=0x244a8f8 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 31 31 32 33 30 34 
[pixma]  00000080:34 32 00 00 
[pixma] setup_udp_socket: Setting up a 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=3.535 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 = 8)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start
[pixma] Received 0x0 = 0 bytes, backend requested 0x8 = 8 bytes
[pixma] No (more) scanner data available, requesting more( blocksize = 2048 =800
[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)!
[pixma] IN   T=7.540 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 = 8)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start
[pixma] Received 0x0 = 0 bytes, backend requested 0x8 = 8 bytes
[pixma] No (more) scanner data available, requesting more( blocksize = 2048 =800
[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)!
[pixma] IN   T=11.544 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 = 8)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start
[pixma] Received 0x0 = 0 bytes, backend requested 0x8 = 8 bytes
[pixma] No (more) scanner data available, requesting more( blocksize = 2048 =800
[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)!
[pixma] IN   T=15.548 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 = 8)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start
[pixma] Received 0x0 = 0 bytes, backend requested 0x8 = 8 bytes
[pixma] No (more) scanner data available, requesting more( blocksize = 2048 =800
[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)!
[pixma] IN   T=19.553 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 = 8)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start
[pixma] Received 0x0 = 0 bytes, backend requested 0x8 = 8 bytes
[pixma] No (more) scanner data available, requesting more( blocksize = 2048 =800
[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)!
[pixma] IN   T=23.557 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 = 8)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start
[pixma] Received 0x0 = 0 bytes, backend requested 0x8 = 8 bytes
[pixma] No (more) scanner data available, requesting more( blocksize = 2048 =800
[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)!
[pixma] IN   T=27.561 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 = 8)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start
[pixma] Received 0x0 = 0 bytes, backend requested 0x8 = 8 bytes
[pixma] No (more) scanner data available, requesting more( blocksize = 2048 =800
[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)!
[pixma] IN   T=31.566 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 = 8)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start
[pixma] Received 0x0 = 0 bytes, backend requested 0x8 = 8 bytes
[pixma] No (more) scanner data available, requesting more( blocksize = 2048 =800
[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)!
[pixma] IN   T=35.570 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 = 524288)
[pixma] bjnp_read_bulk: 0x0 = 0 bytes available at start
[pixma] Received 0x0 = 0 bytes, backend requested 0x80000 = 524288 bytes
[pixma] No (more) scanner data available, requesting more( blocksize = 2048 =800
[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)!
[pixma] IN   T=39.571 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)!
[pixma] Could not read response to command!
[pixma] OUT  T=43.575 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)!
[pixma] Could not read response to command!
[pixma] OUT  T=48.077 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 a 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 a 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 


> If this fails, can you please try the USB connection to make sure this
> is not a problem in the actual backend?

I'll have to try this a bit later on, I'll have to locate a USB cable
first.

-- 
Frode V. Fjeld




More information about the sane-devel mailing list