[sane-devel] Mustek 6000CX dies after the first of three passes in color mode

Thu, 4 Apr 2002 03:03:51 +0200

Hi,

Scanimage stops immediately after the first of three passes in color mode.
Scanimage does not wait for the scanner to finish backtracking. It seems,
scanimage even does not wait for the scanner to start backtracking. I
never did scans in color mode before.

My configuration is somewhat special.

The scanner is connected to a 486 with a very small HD. The 486 does not
have enough space for the compiler and the default environment for the
compiler. Thus I compiled sane on a newer computer without scsi abilities,
mounted the compiled directory via network, and did a 'make install' on
the 486 in the mounted directory. Both computers do run SuSE Linux 7.1.
The newer computer is upgraded to kernel 2.4.18, the older one uses the
default 2.2.18 kernel.

Thanks in advance,

Christian Fughe <c.m.fughe@wtb.tue.nl>

Here comes the logfile from:

 SANE_DEBUG_MUSTEK=5 scanimage --mode Color  2>logfile.txt

---8x---
[sanei_debug] Setting debug level of mustek to 5.
[mustek] SANE mustek backend version 1.0 build 119 from sane-backends-1.0.7-cvs
[mustek] sane_init: authorize != null
[mustek] sane_init: using sanei_scsi_open_extended
[mustek] sane_init: reading config file `mustek.conf'
[mustek] sane_init: config file line 1: ignoring comment line
[mustek] sane_init: config file line 2: ignoring empty line
[mustek] sane_init: config file line 3: ignoring comment line
[mustek] sane_init: config file line 4: ignoring comment line
[mustek] sane_init: config file line 5: ignoring comment line
[mustek] sane_init: config file line 6: ignoring comment line
[mustek] sane_init: config file line 7: ignoring comment line
[mustek] sane_init: config file line 8: ignoring empty line
[mustek] sane_init: config file line 9: ignoring comment line
[mustek] sane_init: config file line 10: trying to attach `scsi MUSTEK * Scanner'
[mustek] attach: trying device /dev/sg0
[mustek] dev_open /dev/sg0
[mustek] dev_open: /dev/sg0 is a SCSI device
[mustek] dev_open: wanted 8 kbytes, got 8 kbytes buffer
[mustek] attach: sending INQUIRY
[mustek] dev_cmd: fd=5, src=0x4019b630, src_size=6, dst=0xbfffdebc, dst_size=96
[mustek] dev_cmd: sending:  12 00 00 00 60 00
[mustek] dev_cmd: receiving:  06 10 10 00 5b 00 00 00 4d 55 53 54 45 4b ff ff
[mustek] dev_cmd: receiving:  4d 46 53 2d 30 36 30 30 30 43 58 ff ff ff ff ff
[mustek] dev_cmd: receiving:  34 2e 30 35 41 52 43 48 45 52 20 20 4c 41 49 20
[mustek] dev_cmd: receiving:  ff ff ff ff ff ff ff ff 00 8f ff ff 01 00 00 00
[mustek] dev_cmd: receiving:  01 03 01 01 00 00 00 00 00 00 00 00 00 00 00 00
[mustek] dev_cmd: receiving:  00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ff
[mustek] dev_cmd: finished: dst_size=96, status=Success
[mustek] scsi_unit_wait_ready: sending TEST_UNIT_READY
[mustek] scsi_unit_wait_ready: TEST_UNIT_READY finished
[mustek] attach: SCSI Vendor: `MUSTEK  ' Model: `MFS-06000CX     ' Rev.: `4.05'
[mustek] attach: SCSI Type: Scanner; ANSI rev.: 0
[mustek] attach: SCSI flags: 
[mustek] attach: inquiry output:
[mustek]  06 10 10 00 5b 00 00 00 4d 55 53 54 45 4b ff ff  ....[...MUSTEK..
[mustek]  4d 46 53 2d 30 36 30 30 30 43 58 ff ff ff ff ff  MFS-06000CX.....
[mustek]  34 2e 30 35 41 52 43 48 45 52 20 20 4c 41 49 20  4.05ARCHER  LAI 
[mustek]  ff ff ff ff ff ff ff ff 00 8f ff ff 01 00 00 00  ................
[mustek]  01 03 01 01 00 00 00 00 00 00 00 00 00 00 00 00  ................
[mustek]  00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ff  ................
[mustek] attach: found Mustek scanner (old firmware format)
[mustek] attach: old firmware revision system
[mustek] attach: firmware revision 4.05
[mustek] attach: scanner id: MFS-06000CX
[mustek] attach: this is probably a Paragon series I or 3-pass scanner
[mustek] attach: this is a three-pass scanner
[mustek] attach: found Mustek MFS-6000CX flatbed scanner, 3-pass
[mustek] sane_init: config file line 11: ignoring comment line
[mustek] sane_init: config file line 12: ignoring comment line
[mustek] sane_init: config file line 13: ignoring comment line
[mustek] sane_init: config file line 14: enabling lineart-fix for /dev/sg0
[mustek] sane_init: config file line 15: ignoring empty line
[mustek] sane_init: config file line 16: trying to attach `scsi SCANNER'
[mustek] sane_init: config file line 17: ignoring comment line
[mustek] sane_init: config file line 18: ignoring comment line
[mustek] sane_init: config file line 19: ignoring comment line
[mustek] sane_init: config file line 20: option lineart-fix ignored, was set before any device name
[mustek] sane_init: config file line 21: ignoring empty line
[mustek] sane_init: config file line 22: trying to attach `/dev/scanner'
[mustek] attach: trying device /dev/scanner
[mustek] dev_open /dev/scanner
[mustek] dev_open: Invalid argument: can't open /dev/scanner as a SCSI device
[mustek] dev_open: Invalid argument: can't open /dev/scanner as an AB306N device
[mustek] dev_open: can't open /dev/scanner
[mustek] sane_init: config file line 23: ignoring comment line
[mustek] sane_init: config file line 24: ignoring comment line
[mustek] sane_init: config file line 25: ignoring comment line
[mustek] sane_init: config file line 26: option lineart-fix ignored, was set before any device name
[mustek] sane_init: config file line 27: ignoring empty line
[mustek] sane_init: config file line 28: ignoring comment line
[mustek] sane_init: config file line 29: ignoring comment line
[mustek] sane_init: config file line 30: ignoring comment line
[mustek] sane_init: config file line 31: ignoring comment line
[mustek] sane_init: config file line 32: ignoring comment line
[mustek] sane_init: end
[mustek] sane_get_devices: 1 devices 
[mustek] sane_get_devices: end
[mustek] sane_open: devicename=/dev/sg0
[mustek] sane_open: finished (handle=0x80517a8)
[mustek] sane_control_option (get option )
[mustek] sane_control_option (get option )
[mustek] sane_get_option_descriptor for option  (active)
[mustek] sane_get_option_descriptor for option "Scan Mode" (active)
[mustek] sane_get_option_descriptor for option mode (active)
[mustek] sane_get_option_descriptor for option fast-gray-mode (inactive)
[mustek] sane_get_option_descriptor for option resolution (active)
[mustek] sane_get_option_descriptor for option depth (inactive)
[mustek] sane_get_option_descriptor for option speed (active)
[mustek] sane_get_option_descriptor for option source (inactive)
[mustek] sane_get_option_descriptor for option preview (active)
[mustek] sane_get_option_descriptor for option fast-preview (active)
[mustek] sane_get_option_descriptor for option "Geometry" (active, advanced)
[mustek] sane_get_option_descriptor for option tl-x (active)
[mustek] sane_get_option_descriptor for option tl-y (active)
[mustek] sane_get_option_descriptor for option br-x (active)
[mustek] sane_control_option (get option br-x)
[mustek] sane_get_option_descriptor for option br-y (active)
[mustek] sane_control_option (get option br-y)
[mustek] sane_get_option_descriptor for option "Enhancement" (active)
[mustek] sane_get_option_descriptor for option brightness (active)
[mustek] sane_get_option_descriptor for option brightness-r (inactive)
[mustek] sane_get_option_descriptor for option brightness-g (inactive)
[mustek] sane_get_option_descriptor for option brightness-b (inactive)
[mustek] sane_get_option_descriptor for option contrast (active)
[mustek] sane_get_option_descriptor for option contrast-r (inactive)
[mustek] sane_get_option_descriptor for option contrast-g (inactive)
[mustek] sane_get_option_descriptor for option contrast-b (inactive)
[mustek] sane_get_option_descriptor for option custom-gamma (active)
[mustek] sane_get_option_descriptor for option gamma-table (inactive)
[mustek] sane_get_option_descriptor for option red-gamma-table (inactive)
[mustek] sane_get_option_descriptor for option green-gamma-table (inactive)
[mustek] sane_get_option_descriptor for option blue-gamma-table (inactive)
[mustek] sane_get_option_descriptor for option quality-cal (inactive)
[mustek] sane_get_option_descriptor for option halftone-size (inactive)
[mustek] sane_get_option_descriptor for option halftone-pattern (inactive)
[mustek] sane_control_option (get option tl-x)
[mustek] sane_control_option (get option tl-y)
[mustek] sane_get_option_descriptor for option mode (active)
[mustek] sane_get_option_descriptor for option mode (active)
[mustek] sane_control_option (set option mode)
[mustek] sane_control_option (get option )
[mustek] sane_get_option_descriptor for option  (active)
[mustek] sane_get_option_descriptor for option "Scan Mode" (active)
[mustek] sane_get_option_descriptor for option mode (active)
[mustek] sane_get_option_descriptor for option fast-gray-mode (inactive)
[mustek] sane_get_option_descriptor for option resolution (active)
[mustek] sane_get_option_descriptor for option depth (inactive)
[mustek] sane_get_option_descriptor for option speed (active)
[mustek] sane_get_option_descriptor for option source (inactive)
[mustek] sane_get_option_descriptor for option preview (active)
[mustek] sane_get_option_descriptor for option fast-preview (active)
[mustek] sane_get_option_descriptor for option "Geometry" (active, advanced)
[mustek] sane_get_option_descriptor for option tl-x (active)
[mustek] sane_get_option_descriptor for option tl-y (active)
[mustek] sane_get_option_descriptor for option br-x (active)
[mustek] sane_control_option (get option br-x)
[mustek] sane_get_option_descriptor for option br-y (active)
[mustek] sane_control_option (get option br-y)
[mustek] sane_get_option_descriptor for option "Enhancement" (active)
[mustek] sane_get_option_descriptor for option brightness (inactive)
[mustek] sane_get_option_descriptor for option brightness-r (active)
[mustek] sane_get_option_descriptor for option brightness-g (active)
[mustek] sane_get_option_descriptor for option brightness-b (active)
[mustek] sane_get_option_descriptor for option contrast (inactive)
[mustek] sane_get_option_descriptor for option contrast-r (active)
[mustek] sane_get_option_descriptor for option contrast-g (active)
[mustek] sane_get_option_descriptor for option contrast-b (active)
[mustek] sane_get_option_descriptor for option custom-gamma (active)
[mustek] sane_get_option_descriptor for option gamma-table (inactive)
[mustek] sane_get_option_descriptor for option red-gamma-table (inactive)
[mustek] sane_get_option_descriptor for option green-gamma-table (inactive)
[mustek] sane_get_option_descriptor for option blue-gamma-table (inactive)
[mustek] sane_get_option_descriptor for option quality-cal (inactive)
[mustek] sane_get_option_descriptor for option halftone-size (inactive)
[mustek] sane_get_option_descriptor for option halftone-pattern (inactive)
[mustek] sane_control_option (get option tl-x)
[mustek] sane_control_option (get option tl-y)
[mustek] sane_control_option (get option tl-x)
[mustek] sane_get_option_descriptor for option br-x (active)
[mustek] sane_control_option (set option br-x)
[mustek] constrain_value: br-x = 215.90 (was 215.90)
[mustek] sane_control_option (get option tl-y)
[mustek] sane_get_option_descriptor for option br-y (active)
[mustek] sane_control_option (set option br-y)
[mustek] constrain_value: br-y = 352.04 (was 352.04)
[mustek] sane_start
[mustek] encode_halftone: standard pattern type 0
[mustek] sane_get_parameters: frame = 2; last_frame = false; depth = 8
[mustek] sane_get_parameters: lines = 997; ppl = 611; bpl = 611
[mustek] encode_resolution: code = 0x18 (24); mode = 0
[mustek] dev_open /dev/sg0
[mustek] dev_open: /dev/sg0 is a SCSI device
[mustek] dev_open: wanted 128 kbytes, got 128 kbytes buffer
[mustek] scsi_area_wait_ready
[mustek] area_and_windows: tlx=0 (0 mm); tly=0 (0 mm); brx=2550 (215 mm); bry=4158 (352 mm)
[mustek] dev_cmd: fd=3, src=0xbfff70b4, src_size=15, dst=(nil), dst_size=0
[mustek] dev_cmd: sending:  04 00 00 00 09 00 09 00 00 00 00 f6 09 3e 10
[mustek] dev_cmd: finished: dst_size=0, status=Success
[mustek] inquiry: sending INQUIRY
[mustek] dev_cmd: fd=3, src=0x4019b630, src_size=6, dst=0xbfff713c, dst_size=96
[mustek] dev_cmd: sending:  12 00 00 00 60 00
[mustek] dev_cmd: receiving:  06 10 10 00 5b 00 00 00 4d 55 53 54 45 4b ff ff
[mustek] dev_cmd: receiving:  4d 46 53 2d 30 36 30 30 30 43 58 ff ff ff ff ff
[mustek] dev_cmd: receiving:  34 2e 30 35 41 52 43 48 45 52 20 20 4c 41 49 20
[mustek] dev_cmd: receiving:  ff ff ff ff ff ff ff ff 00 8f ff ff 01 00 00 00
[mustek] dev_cmd: receiving:  01 03 01 01 00 00 00 00 00 00 00 00 00 00 00 00
[mustek] dev_cmd: receiving:  00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ff
[mustek] dev_cmd: finished: dst_size=96, status=Success
[mustek] area_and_windows: tlx=0 (0 mm); tly=0 (0 mm); brx=2550 (215 mm); bry=4158 (352 mm)
[mustek] dev_cmd: fd=3, src=0xbfff7124, src_size=15, dst=(nil), dst_size=0
[mustek] dev_cmd: sending:  04 00 00 00 09 00 09 00 00 00 00 f6 09 3e 10
[mustek] dev_cmd: finished: dst_size=0, status=Success
[mustek] adf_and_backtrack: backtrack: yes; ADF: no; TA: no
[mustek] dev_cmd: fd=3, src=0xbfff7194, src_size=6, dst=(nil), dst_size=0
[mustek] dev_cmd: sending:  10 00 00 00 82 00
[mustek] dev_cmd: finished: dst_size=0, status=Success
[mustek] mode_select: resolution_code=24 (0x18)
[mustek] dev_cmd: fd=3, src=0xbfff7188, src_size=17, dst=(nil), dst_size=0
[mustek] dev_cmd: sending:  15 00 00 00 0b 00 8b 18 0c 0c 00 01 00 00 00 00
[mustek] dev_cmd: sending:  00
[mustek] dev_cmd: finished: dst_size=0, status=Success
[mustek] gamma_correction: no custom table selected -- exititing
[mustek] start_scan
[mustek] dev_cmd: fd=3, src=0xbfff7194, src_size=6, dst=(nil), dst_size=0
[mustek] dev_cmd: sending:  1b 00 00 00 49 00
[mustek] dev_cmd: finished: dst_size=0, status=Success
[mustek] gamma_correction: no custom table selected -- exititing
[mustek] dev_cmd: fd=3, src=0x4019b654, src_size=6, dst=0xbfff7194, dst_size=6
[mustek] dev_cmd: sending:  0f 00 00 00 06 00
[mustek] dev_cmd: receiving:  00 62 02 e7 03 00
[mustek] dev_cmd: finished: dst_size=6, status=Success
[mustek] get_image_status: bytes_per_line=610, lines=999 (offset = 0)
[mustek] sane_get_parameters: frame = 2; last_frame = false; depth = 8
[mustek] sane_get_parameters: lines = 999; ppl = 610; bpl = 610
[mustek] reader_process: 107 lines per buffer, 610 bytes per line, 65270 bytes per buffer
[mustek] reader_process: limiting block read to 16450 buffers (999 lines)
[mustek] dev_block_read_start: entering block for 999 lines
[mustek] reader_process: buffer 1: entering read request for 65270 bytes (buffer 1)
[mustek] reader_process: buffer 1: entered (line 107 of 999, buffer 1)
[mustek] reader_process: buffer 2: entering read request for 65270 bytes (buffer 2)
[mustek] reader_process: buffer 2: entered (line 214 of 999, buffer 2)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] sane_read
[mustek] reader_process: buffer 1 is ready, wanted 65270, got 65270 bytes
[mustek] reader_process: buffer 1: sending 65270 bytes to output_data
[mustek] output_data: data=0x80531d8, lpb=107, bpl=610, extra=(nil)
[mustek] output_data: write 107 lpb; 610 bpl
[mustek] sane_read: read full buffer of 32768 bytes (32768 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65270 bytes (buffer 3)
[mustek] reader_process: buffer 1: entered (line 321 of 999, buffer 3)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65270, got 65270 bytes
[mustek] reader_process: buffer 2: sending 65270 bytes to output_data
[mustek] output_data: data=0x80630ce, lpb=107, bpl=610, extra=(nil)
[mustek] output_data: write 107 lpb; 610 bpl
[mustek] sane_read: read full buffer of 32768 bytes (65536 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (98304 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65270 bytes (buffer 4)
[mustek] reader_process: buffer 2: entered (line 428 of 999, buffer 4)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65270, got 65270 bytes
[mustek] reader_process: buffer 1: sending 65270 bytes to output_data
[mustek] output_data: data=0x80531d8, lpb=107, bpl=610, extra=(nil)
[mustek] output_data: write 107 lpb; 610 bpl
[mustek] sane_read: read full buffer of 32768 bytes (131072 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (163840 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65270 bytes (buffer 5)
[mustek] reader_process: buffer 1: entered (line 535 of 999, buffer 5)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65270, got 65270 bytes
[mustek] reader_process: buffer 2: sending 65270 bytes to output_data
[mustek] output_data: data=0x80630ce, lpb=107, bpl=610, extra=(nil)
[mustek] output_data: write 107 lpb; 610 bpl
[mustek] sane_read: read full buffer of 32768 bytes (196608 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (229376 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65270 bytes (buffer 6)
[mustek] reader_process: buffer 2: entered (line 642 of 999, buffer 6)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65270, got 65270 bytes
[mustek] reader_process: buffer 1: sending 65270 bytes to output_data
[mustek] output_data: data=0x80531d8, lpb=107, bpl=610, extra=(nil)
[mustek] output_data: write 107 lpb; 610 bpl
[mustek] sane_read: read full buffer of 32768 bytes (262144 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (294912 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65270 bytes (buffer 7)
[mustek] reader_process: buffer 1: entered (line 749 of 999, buffer 7)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65270, got 65270 bytes
[mustek] reader_process: buffer 2: sending 65270 bytes to output_data
[mustek] output_data: data=0x80630ce, lpb=107, bpl=610, extra=(nil)
[mustek] output_data: write 107 lpb; 610 bpl
[mustek] sane_read: read full buffer of 32768 bytes (327680 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (360448 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65270 bytes (buffer 8)
[mustek] reader_process: buffer 2: entered (line 856 of 999, buffer 8)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65270, got 65270 bytes
[mustek] reader_process: buffer 1: sending 65270 bytes to output_data
[mustek] output_data: data=0x80531d8, lpb=107, bpl=610, extra=(nil)
[mustek] output_data: write 107 lpb; 610 bpl
[mustek] sane_read: read full buffer of 32768 bytes (393216 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (425984 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65270 bytes (buffer 9)
[mustek] reader_process: buffer 1: entered (line 963 of 999, buffer 9)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65270, got 65270 bytes
[mustek] reader_process: buffer 2: sending 65270 bytes to output_data
[mustek] output_data: data=0x80630ce, lpb=107, bpl=610, extra=(nil)
[mustek] output_data: write 107 lpb; 610 bpl
[mustek] sane_read: read full buffer of 32768 bytes (458752 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (491520 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 21960 bytes (buffer 10)
[mustek] reader_process: buffer 2: entered (line 999 of 999, buffer 10)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65270, got 65270 bytes
[mustek] reader_process: buffer 1: sending 65270 bytes to output_data
[mustek] output_data: data=0x80531d8, lpb=107, bpl=610, extra=(nil)
[mustek] output_data: write 107 lpb; 610 bpl
[mustek] sane_read: read full buffer of 32768 bytes (524288 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (557056 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 21960, got 21960 bytes
[mustek] reader_process: buffer 2: sending 21960 bytes to output_data
[mustek] output_data: data=0x80630ce, lpb=36, bpl=610, extra=(nil)
[mustek] output_data: write 36 lpb; 610 bpl
[mustek] sane_read: read full buffer of 32768 bytes (589824 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] sane_read: read last buffer of 19566 bytes (609390 bytes total)
[mustek] sane_read
[mustek] sane_read: pipe was closed ... finishing pass 1
[mustek] do_eof: closing pipe
[mustek] sane_start
[mustek] sane_get_parameters: frame = 3; last_frame = false; depth = 8
[mustek] sane_get_parameters: lines = 999; ppl = 610; bpl = 610
[mustek] scsi_area_wait_ready
[mustek] area_and_windows: tlx=0 (0 mm); tly=0 (0 mm); brx=2550 (215 mm); bry=4158 (352 mm)
[mustek] dev_cmd: fd=3, src=0xbfff70b4, src_size=15, dst=(nil), dst_size=0
[mustek] dev_cmd: sending:  04 00 00 00 09 00 09 00 00 00 00 f6 09 3e 10
[mustek] dev_cmd: finished: dst_size=0, status=Success
[mustek] inquiry: sending INQUIRY
[mustek] dev_cmd: fd=3, src=0x4019b630, src_size=6, dst=0xbfff713c, dst_size=96
[mustek] dev_cmd: sending:  12 00 00 00 60 00
[mustek] dev_cmd: receiving:  8c 71 ff bf 1d b8 18 40 80 38 1a 40 00 00 00 00
[mustek] dev_cmd: receiving:  e7 03 00 00 62 02 00 00 62 02 00 00 00 00 00 00
[mustek] dev_cmd: receiving:  9c 71 ff bf de 62 19 40 40 9b ab 3c 29 4f 04 00
[mustek] dev_cmd: receiving:  9c 71 ff bf dd bb 18 40 a8 17 05 08 00 00 00 00
[mustek] dev_cmd: receiving:  60 47 18 40 ac bb 18 40 80 38 1a 40 31 00 00 00
[mustek] dev_cmd: receiving:  fc 72 ff bf 92 66 19 40 a8 17 05 08 00 00 00 00
[mustek] dev_cmd: finished: dst_size=96, status=Device busy
[mustek] sane_start: inquiry command failed: Device busy
[mustek] do_stop
[mustek] Scanning time was 26 seconds, 22 kB/s
[mustek] Scanned 0 bytes, expected 609390 bytes
[mustek] do_stop: terminating reader process
[mustek] do_stop: reader process terminated: Success
[mustek] do_stop: closing scanner
scanimage: sane_start: Device busy
[mustek] sane_cancel
[mustek] sane_cancel finished
[mustek] sane_close: handle=0x80517a8
[mustek] sane_exit