[sane-devel] mustek backend - is everything ok?

Christoph Kukulies kuku@gilberto.physik.rwth-aachen.de
Wed, 19 Sep 2001 09:26:50 +0200 (CEST)


Yesterday I installed the backend from sane-cvs-2001-09-13.tar.gz 
to make sure I get the latest fixes wrt the mustek backend.

I don't know if everything is optimally tuned and thus I'm seeking 
for some advice here.

E.g. I scanned an area like:

scanimage --resolution 300 -l 50 -t 50 -x 100 -y 100 >l1 2>l2

and got 75 KB/s

Doing the same, but x=200:

scanimage --resolution 300 -l 50 -t 50 -x 200 -y 100 >l1 2>l2

I get 126 KB/s.

This may be normal because a whole line of a scan is probably
more effective than a portion of it, although I'm wondering whether
I could tune the parameters a bit to get even better
results. Also, what are good scan rates with these Mustek 12000SP
scanners?

Henning, does this log reflect that I have the latest mustek backend?

-- 
Chris Christoph P. U. Kukulies kuku@gil.physik.rwth-aachen.de

[sanei_debug] Setting debug level of mustek to 5.
[mustek] SANE mustek backend version 1.0 build 113 from sane-backends-1.0.5
[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: strip-height set to 1 inches
[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] sane_init: config file line 11: ignoring comment line
[mustek] sane_init: config file line 12: option buffersize ignored, was set before any device name
[mustek] sane_init: config file line 13: option blocksize ignored, was set before any device name
[mustek] sane_init: config file line 14: option lineart-fix ignored, was set before any device name
[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: /dev/scanner is a SCSI device
[mustek] dev_open: wanted 8 kbytes, got 8 kbytes buffer
[mustek] attach: sending INQUIRY
[mustek] scsi_unit_wait_ready: sending TEST_UNIT_READY
[mustek] scsi_unit_wait_ready: TEST_UNIT_READY finished
[mustek] attach: SCSI Vendor: `SCANNER ' Model: `                ' Rev.: `1.11'
[mustek] attach: SCSI Type: Scanner; ANSI rev.: 1
[mustek] attach: SCSI flags: 
[mustek] attach: inquiry output:
[mustek]  06 00 01 01 5b 01 00 00 53 43 41 4e 4e 45 52 20  ....[...SCANNER 
[mustek]  20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20                  
[mustek]  31 2e 31 31 4d 55 53 54 45 4b 20 20 4d 46 53 2d  1.11MUSTEK  MFS-
[mustek]  31 32 30 30 30 53 50 20 00 cf ff ff 01 00 00 00  12000SP ........
[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 (new firmware format)
[mustek] attach: old firmware revision system
[mustek] attach: firmware revision 1.11
[mustek] attach: scanner id: MFS-12000SP
[mustek] attach: this is probably a Paragon series I or 3-pass scanner
[mustek] attach: this is a single-pass scanner
[mustek] attach: scanner has block line-distance correction
[mustek] attach: found Mustek MFS-12000SP flatbed scanner, 1-pass
[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: enabling lineart-fix for /dev/scanner
[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/scanner
[mustek] sane_open: finished (handle=0x805e5e8)
[mustek] sane_control_option (get option (null))
[mustek] sane_control_option (get option (null))
[mustek] sane_get_option_descriptor for option "Number of options" (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 (inactive)
[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 (inactive)
[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 resolution (active)
[mustek] sane_get_option_descriptor for option resolution (active)
[mustek] sane_control_option (set option resolution)
[mustek] constrain_value: resolution = 300.00 (was 300.00)
[mustek] sane_get_option_descriptor for option tl-x (active)
[mustek] sane_get_option_descriptor for option tl-x (active)
[mustek] sane_control_option (set option tl-x)
[mustek] constrain_value: tl-x = 50.00 (was 50.00)
[mustek] sane_get_option_descriptor for option tl-y (active)
[mustek] sane_get_option_descriptor for option tl-y (active)
[mustek] sane_control_option (set option tl-y)
[mustek] constrain_value: tl-y = 50.00 (was 50.00)
[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 = 217.00 (was 250.00)
scanimage: rounded value of br-x from 250 to 217
[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 = 150.00 (was 150.00)
[mustek] sane_start
[mustek] encode_halftone: standard pattern type 0
[mustek] sane_get_parameters: frame = 0; last_frame = true; depth = 8
[mustek] sane_get_parameters: lines = 1181; ppl = 1972; bpl = 1972
[mustek] encode_resolution: code = 0x12c (300); mode = 0
[mustek] dev_open /dev/scanner
[mustek] dev_open: /dev/scanner is a SCSI device
[mustek] dev_open: wanted 128 kbytes, got 128 kbytes buffer
[mustek] scsi_inquiry_wait_ready: sending INQUIRY
[mustek] inquiry: sending INQUIRY
[mustek] scsi_inquiry_wait_ready: INQUIRY finished
[mustek] inquiry: sending INQUIRY
[mustek] area_and_windows: tlx=2362 (49 mm); tly=2362 (49 mm); brx=10252 (217 mm); bry=7087 (150 mm)
[mustek] adf_and_backtrack: backtrack: yes; ADF: no; TA: no
[mustek] mode_select: resolution_code=300 (0x12c)
[mustek] gamma_correction: no custom table selected -- exititing
[mustek] start_scan
[mustek] start_scan: using block mode
[mustek] gamma_correction: no custom table selected -- exititing
[mustek] line_distance: got factor=65535, (r/g/b)=(0/4/8)
[mustek] get_image_status: bytes_per_line=1972, lines=1182 (offset = 0)
[mustek] sane_get_parameters: frame = 0; last_frame = true; depth = 8
[mustek] sane_get_parameters: lines = 1182; ppl = 1972; bpl = 1972
[mustek] sane_read
[mustek] reader_process: 33 lines per buffer, 1972 bytes per line, 65076 bytes per buffer
[mustek] reader_process: limiting block read to 16499 buffers (1182 lines)
[mustek] dev_block_read_start: entering block for 1182 lines
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 1)
[mustek] reader_process: buffer 1: entered (line 33 of 1182, buffer 1)
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 2)
[mustek] reader_process: buffer 2: entered (line 66 of 1182, buffer 2)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 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 65076 bytes (buffer 3)
[mustek] reader_process: buffer 1: entered (line 99 of 1182, buffer 3)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 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 65076 bytes (buffer 4)
[mustek] reader_process: buffer 2: entered (line 132 of 1182, buffer 4)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 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 65076 bytes (buffer 5)
[mustek] reader_process: buffer 1: entered (line 165 of 1182, buffer 5)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 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 65076 bytes (buffer 6)
[mustek] reader_process: buffer 2: entered (line 198 of 1182, buffer 6)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 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 65076 bytes (buffer 7)
[mustek] reader_process: buffer 1: entered (line 231 of 1182, buffer 7)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 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 65076 bytes (buffer 8)
[mustek] reader_process: buffer 2: entered (line 264 of 1182, buffer 8)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 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 65076 bytes (buffer 9)
[mustek] reader_process: buffer 1: entered (line 297 of 1182, buffer 9)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 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 65076 bytes (buffer 10)
[mustek] reader_process: buffer 2: entered (line 330 of 1182, buffer 10)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 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 1: entering read request for 65076 bytes (buffer 11)
[mustek] reader_process: buffer 1: entered (line 363 of 1182, buffer 11)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (589824 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (622592 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 12)
[mustek] reader_process: buffer 2: entered (line 396 of 1182, buffer 12)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (655360 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (688128 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 13)
[mustek] reader_process: buffer 1: entered (line 429 of 1182, buffer 13)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (720896 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (753664 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 14)
[mustek] reader_process: buffer 2: entered (line 462 of 1182, buffer 14)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (786432 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (819200 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 15)
[mustek] reader_process: buffer 1: entered (line 495 of 1182, buffer 15)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (851968 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (884736 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 16)
[mustek] reader_process: buffer 2: entered (line 528 of 1182, buffer 16)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (917504 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (950272 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 17)
[mustek] reader_process: buffer 1: entered (line 561 of 1182, buffer 17)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (983040 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1015808 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 18)
[mustek] reader_process: buffer 2: entered (line 594 of 1182, buffer 18)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1048576 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1081344 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 19)
[mustek] reader_process: buffer 1: entered (line 627 of 1182, buffer 19)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1114112 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1146880 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 20)
[mustek] reader_process: buffer 2: entered (line 660 of 1182, buffer 20)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1179648 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1212416 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 21)
[mustek] reader_process: buffer 1: entered (line 693 of 1182, buffer 21)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1245184 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1277952 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 22)
[mustek] reader_process: buffer 2: entered (line 726 of 1182, buffer 22)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1310720 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1343488 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 23)
[mustek] reader_process: buffer 1: entered (line 759 of 1182, buffer 23)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1376256 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1409024 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 24)
[mustek] reader_process: buffer 2: entered (line 792 of 1182, buffer 24)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1441792 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1474560 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 25)
[mustek] reader_process: buffer 1: entered (line 825 of 1182, buffer 25)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1507328 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1540096 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 26)
[mustek] reader_process: buffer 2: entered (line 858 of 1182, buffer 26)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1572864 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1605632 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 27)
[mustek] reader_process: buffer 1: entered (line 891 of 1182, buffer 27)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1638400 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1671168 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 28)
[mustek] reader_process: buffer 2: entered (line 924 of 1182, buffer 28)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1703936 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1736704 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 29)
[mustek] reader_process: buffer 1: entered (line 957 of 1182, buffer 29)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1769472 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1802240 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 30)
[mustek] reader_process: buffer 2: entered (line 990 of 1182, buffer 30)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1835008 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1867776 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 31)
[mustek] reader_process: buffer 1: entered (line 1023 of 1182, buffer 31)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1900544 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1933312 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 32)
[mustek] reader_process: buffer 2: entered (line 1056 of 1182, buffer 32)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (1966080 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (1998848 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 33)
[mustek] reader_process: buffer 1: entered (line 1089 of 1182, buffer 33)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (2031616 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (2064384 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 65076 bytes (buffer 34)
[mustek] reader_process: buffer 2: entered (line 1122 of 1182, buffer 34)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (2097152 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (2129920 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 1: entering read request for 65076 bytes (buffer 35)
[mustek] reader_process: buffer 1: entered (line 1155 of 1182, buffer 35)
[mustek] reader_process: buffer 2: waiting for request to be ready
[mustek] reader_process: buffer 2 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 2: sending 65076 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (2162688 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (2195456 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] reader_process: buffer 2: entering read request for 53244 bytes (buffer 36)
[mustek] reader_process: buffer 2: entered (line 1182 of 1182, buffer 36)
[mustek] reader_process: buffer 1: waiting for request to be ready
[mustek] reader_process: buffer 1 is ready, wanted 65076, got 65076 bytes
[mustek] reader_process: buffer 1: sending 65076 bytes to output_data
[mustek] output_data: data=0x807d740, lpb=33, bpl=1972, extra=0x40327008
[mustek] output_data: write 33 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (2228224 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (2260992 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 53244, got 53244 bytes
[mustek] reader_process: buffer 2: sending 53244 bytes to output_data
[mustek] output_data: data=0x808d574, lpb=27, bpl=1972, extra=0x40327008
[mustek] output_data: write 27 lpb; 1972 bpl
[mustek] sane_read: read full buffer of 32768 bytes (2293760 total bytes)
[mustek] sane_read
[mustek] sane_read: read full buffer of 32768 bytes (2326528 total bytes)
[mustek] sane_read
[mustek] output_data: end
[mustek] sane_read: read last buffer of 4376 bytes (2330904 bytes total)
[mustek] sane_read
[mustek] sane_read: pipe was closed ... calling do_stop
[mustek] do_stop
[mustek] Scanning time was 18 seconds, 126 kB/s
[mustek] Scanned 2330904 bytes as expected
[mustek] do_stop: terminating reader process
[mustek] sigterm_handler: signal 15
[mustek] do_stop: reader process terminated: Success
[mustek] do_stop: closing scanner
[mustek] do_eof: closing pipe
[mustek] sane_cancel
[mustek] sane_cancel finished
[mustek] sane_close: handle=0x805e5e8
[mustek] sane_exit