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
