[sane-devel] HP7400c scanner with kernel 2.6 doesn't work

Sietse Achterop sietse@cs.rug.nl
Mon, 10 May 2004 21:16:08 +0200


     Hello list,

My hp7400c scanner (avision-backend), with works properly
under windows 98, doesn't work under sane 1.0.14.
       (however, very seldom it did perform a proper scan)

And of course I'm turning to this list in the hope somebody
can help me in some way.


I looked in the mailing lists, but to no avail.
A number of times similar problems are mentioned with other
scanners (epson), but the issue doesn't seem to be resolved.
   (the bulk messages mentioned below)

Sane stops functioning after the first try beyond "scanimage -L".

My setup:
   Pentium II machine, debian unstable, vanilla kernel 2.6.5 or 2.6.6.
   using usb interface for the scanner.
   hotplugging seems to work, 5 other different usb devices work ok.

The described behaviour occurs with:
     1 the deb-packages from unstable (also 1.0.14)
     2 executable make from recent sane-backends-2004-05-07.tar.gz sources.
           (configure; make; make install)

The sane-troubleshoot program ends with:
     dlopen (libsane.so) succeeded
     --> sane_init ()
     <-- sane_init (): Success
     * SANE version 1.0.14
     --> sane_open (test:1)
     <-- sane_open (test:1): Invalid argument
     --> sane_exit ()


sane-find-scanner
    always finds the device, e.g.:
found USB scanner (vendor=0x03f0 [hp], product=0x0801 [hp scanjet 7400c]) at libusb:001:009

scanimage -L
    only sees the device directly after plugging in, e.g.:
device `avision:libusb:001:009' is a Hewlett-Packard ScanJet 7400c flatbed scanner

scanimage --help
    also works as expected the first time, reporting the avision options.

BUT after any proper scan command it seems to start but after a while
it hangs.
If the first command is:

export SANE_DEBUG_AVISION=7
scanimage --source Normal -l 85mm -t 175mm -x 40mm -y 40mm 2>log > tst.png

then it seems to scan the intended area but stops or keeps scanning until
it gets stuck at a mechanical end.

There always is an usbfs error message when the application hangs, for
example from /var/log/messages:

localhost kernel: usb 1-1: bulk timeout on ep2in
localhost kernel: usbfs: USBDEVFS_BULK failed dev 7 ep 0x83 len 4096 ret -84

So, is it a problem with the 2.6 kernel, usbfs, or with sane?
If would be nice if somebody could help me a step forward.

Please find below the contents of the log file created by the above command.
  (the last part is because I removed the power from the scanner)

      Regards,
       Sietse Achterop



======================================================

[sanei_debug] Setting debug level of avision to 7.
[avision] sane_init: parsing config line ""
[avision] sane_init: config file line 1: ignoring empty line
[avision] sane_init: parsing config line "# This are the possible options. Normally any scanner"
[avision] sane_init: config file line 2: ignoring comment line
[avision] sane_init: parsing config line "# should work just fine without them - and they are only"
[avision] sane_init: config file line 3: ignoring comment line
[avision] sane_init: parsing config line "# needed for test and debugging. So if you experience problems"
[avision] sane_init: config file line 4: ignoring comment line
[avision] sane_init: parsing config line "# and you solve them with enabling options here, please notify"
[avision] sane_init: config file line 5: ignoring comment line
[avision] sane_init: parsing config line "# the SANE/Avision maintainer."
[avision] sane_init: config file line 6: ignoring comment line
[avision] sane_init: parsing config line ""
[avision] sane_init: config file line 7: ignoring empty line
[avision] sane_init: parsing config line "#option disable-gamma-table"
[avision] sane_init: config file line 8: ignoring comment line
[avision] sane_init: parsing config line "#option disable-calibration"
[avision] sane_init: config file line 9: ignoring comment line
[avision] sane_init: parsing config line "#option old-calibration"
[avision] sane_init: config file line 10: ignoring comment line
[avision] sane_init: parsing config line "#option one-calib-only"
[avision] sane_init: config file line 11: ignoring comment line
[avision] sane_init: parsing config line "#option force-a4"
[avision] sane_init: config file line 12: ignoring comment line
[avision] sane_init: parsing config line "#option disable-c5-guard"
[avision] sane_init: config file line 13: ignoring comment line
[avision] sane_init: parsing config line ""
[avision] sane_init: config file line 14: ignoring empty line
[avision] sane_init: parsing config line "usb 0x03f0 0x0801"
[avision] sane_init: config file line 15: trying to attach USB:`usb 0x03f0 0x0801'
[avision] attach: (Version: 1.0 Build: 92)
[avision] attach: opening libusb:001:007
[avision] attach: sending standard INQUIRY
[avision] filling command to have a length of 10, was: 6
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] attach: Inquiry gives mfg=hp, model=scanjet 7400c, product revision=0.8A.
[avision] attach: Found model: 36
[avision] attach: reading firmware status
[avision] get_firmware_status
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] get_firmware_status: raw data:
[avision]   [0] 00000001b   1o   1d  1x
[avision]   [1] 00000000b   0o   0d  0x
[avision]   [2] 00000000b   0o   0d  0x
[avision]   [3] 00000010b   2o   2d  2x
[avision]   [4] 00111101b  75o  61d 3dx
[avision]   [5] 00000000b   0o   0d  0x
[avision]   [6] 00000000b   0o   0d  0x
[avision]   [7] 00000000b   0o   0d  0x
[avision] get_firmware_status: [0]  needs firmware 1
[avision] get_firmware_status: [2]  side edge: 0
[avision] attach: reading flash ram info
[avision] get_flash_ram_info
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] get_flash_ram_info: raw data:
[avision]   [0] 00000001b   1o   1d  1x
[avision]   [1] 00001010b  12o  10d  ax
[avision]   [2] 00000000b   0o   0d  0x
[avision]   [3] 00000000b   0o   0d  0x
[avision]   [4] 00000000b   0o   0d  0x
[avision]   [5] 00000100b   4o   4d  4x
[avision]   [6] 00000000b   0o   0d  0x
[avision]   [7] 00000000b   0o   0d  0x
[avision]   [8] 00000000b   0o   0d  0x
[avision]   [9] 00001000b  10o   8d  8x
[avision]   [10] 00000000b   0o   0d  0x
[avision]   [11] 00000000b   0o   0d  0x
[avision]   [12] 00000000b   0o   0d  0x
[avision]   [13] 00000000b   0o   0d  0x
[avision]   [14] 00000000b   0o   0d  0x
[avision]   [15] 00000000b   0o   0d  0x
[avision]   [16] 00000010b   2o   2d  2x
[avision]   [17] 00111101b  75o  61d 3dx
[avision]   [18] 00000000b   0o   0d  0x
[avision]   [19] 00000000b   0o   0d  0x
[avision]   [20] 00000000b   0o   0d  0x
[avision]   [21] 00000000b   0o   0d  0x
[avision]   [22] 00000000b   0o   0d  0x
[avision]   [23] 00000000b   0o   0d  0x
[avision]   [24] 00000000b   0o   0d  0x
[avision]   [25] 00000000b   0o   0d  0x
[avision]   [26] 00000000b   0o   0d  0x
[avision]   [27] 00000000b   0o   0d  0x
[avision]   [28] 00000000b   0o   0d  0x
[avision]   [29] 00000000b   0o   0d  0x
[avision]   [30] 00000000b   0o   0d  0x
[avision]   [31] 00000000b   0o   0d  0x
[avision]   [32] 00000000b   0o   0d  0x
[avision]   [33] 00000000b   0o   0d  0x
[avision]   [34] 00000000b   0o   0d  0x
[avision]   [35] 00000000b   0o   0d  0x
[avision]   [36] 00000000b   0o   0d  0x
[avision]   [37] 00000000b   0o   0d  0x
[avision]   [38] 00000000b   0o   0d  0x
[avision]   [39] 00000000b   0o   0d  0x
[avision] get_flash_ram_info: [0]    data type 1
[avision] get_flash_ram_info: [1]    Ability1: FMDBG(r) FIRMWARE(r/w)
[avision] get_flash_ram_info: [2-5]   size CTAB: 4
[avision] get_flash_ram_info: [6-9]   size FIRMWARE: 8
[avision] get_flash_ram_info: [10-13] size RAWLINE: 0
[avision] get_flash_ram_info: [14-17] size FMDBG: 573
[avision] get_flash_ram_info: [18-21] size FPGA: 0
[avision] get_flash_ram_info: [22-25] size FONT: 0
[avision] get_flash_ram_info: [26-29] size RESERVED: 0
[avision] get_flash_ram_info: [30-33] size RESERVED: 0
[avision] attach: sending Avision INQUIRY
[avision] filling command to have a length of 10, was: 6
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] attach: raw data:
[avision]   [0] 00000110b   6o   6d  6x
[avision]   [1] 00000000b   0o   0d  0x
[avision]   [2] 00000010b   2o   2d  2x
[avision]   [3] 00000010b   2o   2d  2x
[avision]   [4] 01011011b 133o  91d 5bx
[avision]   [5] 00000000b   0o   0d  0x
[avision]   [6] 00000000b   0o   0d  0x
[avision]   [7] 00000000b   0o   0d  0x
[avision]   [8] 01101000b 150o 104d 68x
[avision]   [9] 01110000b 160o 112d 70x
[avision]   [10] 00100000b  40o  32d 20x
[avision]   [11] 00100000b  40o  32d 20x
[avision]   [12] 00100000b  40o  32d 20x
[avision]   [13] 00100000b  40o  32d 20x
[avision]   [14] 00100000b  40o  32d 20x
[avision]   [15] 00100000b  40o  32d 20x
[avision]   [16] 01110011b 163o 115d 73x
[avision]   [17] 01100011b 143o  99d 63x
[avision]   [18] 01100001b 141o  97d 61x
[avision]   [19] 01101110b 156o 110d 6ex
[avision]   [20] 01101010b 152o 106d 6ax
[avision]   [21] 01100101b 145o 101d 65x
[avision]   [22] 01110100b 164o 116d 74x
[avision]   [23] 00100000b  40o  32d 20x
[avision]   [24] 00110111b  67o  55d 37x
[avision]   [25] 00110100b  64o  52d 34x
[avision]   [26] 00110000b  60o  48d 30x
[avision]   [27] 00110000b  60o  48d 30x
[avision]   [28] 01100011b 143o  99d 63x
[avision]   [29] 00100000b  40o  32d 20x
[avision]   [30] 00100000b  40o  32d 20x
[avision]   [31] 00100000b  40o  32d 20x
[avision]   [32] 00110000b  60o  48d 30x
[avision]   [33] 00101110b  56o  46d 2ex
[avision]   [34] 00111000b  70o  56d 38x
[avision]   [35] 01001101b 115o  77d 4dx
[avision]   [36] 10100001b 241o 161d a1x
[avision]   [37] 00001100b  14o  12d  cx
[avision]   [38] 00001100b  14o  12d  cx
[avision]   [39] 01100111b 147o 103d 67x
[avision]   [40] 00001001b  11o   9d  9x
[avision]   [41] 01100000b 140o  96d 60x
[avision]   [42] 00001001b  11o   9d  9x
[avision]   [43] 01100000b 140o  96d 60x
[avision]   [44] 00001001b  11o   9d  9x
[avision]   [45] 01100000b 140o  96d 60x
[avision]   [46] 00001001b  11o   9d  9x
[avision]   [47] 01100000b 140o  96d 60x
[avision]   [48] 00000000b   0o   0d  0x
[avision]   [49] 00000000b   0o   0d  0x
[avision]   [50] 11011001b 331o 217d d9x
[avision]   [51] 10010110b 226o 150d 96x
[avision]   [52] 11010011b 323o 211d d3x
[avision]   [53] 01000000b 100o  64d 40x
[avision]   [54] 00001000b  10o   8d  8x
[avision]   [55] 00001000b  10o   8d  8x
[avision]   [56] 00000010b   2o   2d  2x
[avision]   [57] 00001000b  10o   8d  8x
[avision]   [58] 00001000b  10o   8d  8x
[avision]   [59] 00001000b  10o   8d  8x
[avision]   [60] 11000000b 300o 192d c0x
[avision]   [61] 11010010b 322o 210d d2x
[avision]   [62] 00100000b  40o  32d 20x
[avision]   [63] 00000101b   5o   5d  5x
[avision]   [64] 00000001b   1o   1d  1x
[avision]   [65] 00000001b   1o   1d  1x
[avision]   [66] 00000010b   2o   2d  2x
[avision]   [67] 00000011b   3o   3d  3x
[avision]   [68] 00000100b   4o   4d  4x
[avision]   [69] 00000101b   5o   5d  5x
[avision]   [70] 00000000b   0o   0d  0x
[avision]   [71] 00000000b   0o   0d  0x
[avision]   [72] 00000000b   0o   0d  0x
[avision]   [73] 00000000b   0o   0d  0x
[avision]   [74] 00000000b   0o   0d  0x
[avision]   [75] 00001111b  17o  15d  fx
[avision]   [76] 00000000b   0o   0d  0x
[avision]   [77] 00000101b   5o   5d  5x
[avision]   [78] 11011100b 334o 220d dcx
[avision]   [79] 00000101b   5o   5d  5x
[avision]   [80] 11011100b 334o 220d dcx
[avision]   [81] 00001001b  11o   9d  9x
[avision]   [82] 11111000b 370o 248d f8x
[avision]   [83] 00010000b  20o  16d 10x
[avision]   [84] 01101000b 150o 104d 68x
[avision]   [85] 00001001b  11o   9d  9x
[avision]   [86] 11111000b 370o 248d f8x
[avision]   [87] 00010000b  20o  16d 10x
[avision]   [88] 01101000b 150o 104d 68x
[avision]   [89] 00001001b  11o   9d  9x
[avision]   [90] 01100000b 140o  96d 60x
[avision]   [91] 00000110b   6o   6d  6x
[avision]   [92] 00000111b   7o   7d  7x
[avision]   [93] 10001000b 210o 136d 88x
[avision]   [94] 00000000b   0o   0d  0x
[avision]   [95] 00000000b   0o   0d  0x
[avision] attach: [8-15]  Vendor id.:      "hp      "
[avision] attach: [16-31] Product id.:     "scanjet 7400c   "
[avision] attach: [32-35] Product rev.:    "0.8M"
[avision] attach: [36]    Bitfield: ADF 3-pass colorBGR color plane
[avision] attach: [37]    Optical res.:    1200 dpi
[avision] attach: [38]    Maximum res.:    1200 dpi
[avision] attach: [39]    Bitfield1: Q_SCAN EXTENDET_RES NEW_PROTOCOL AVISION
[avision] attach: [40-41] X res. in gray:  2400 dpi
[avision] attach: [42-43] Y res. in gray:  2400 dpi
[avision] attach: [44-45] X res. in color: 2400 dpi
[avision] attach: [46-47] Y res. in color: 2400 dpi
[avision] attach: [48-49] USB max read:    0
[avision] attach: [50]    ESA1: LIGHT_CONTROL BUTTON_CONTROL SW_CALIB NEED_SW_GAMMA XYRES_DIFFERENT
[avision] attach: [51]    ESA2: EXPOSURE_CTRL SUPPORTS_QUALITY_SPEED_CAL HAS_PUSH_BUTTON NEW_CAL_METHOD_3x3_MATRIX_(NO_GAMMA_TABLE)
[avision] attach: [52]    ESA3: GRAY_WHITE SUPPORTS_GAIN_CONTROL 3x3COL_TABLE POWER_SAVING_TIMER NVM_DATA_REC
[avision] attach: [53]    line difference (software color pack): 64
[avision] attach: [54]    color mode pixel boundary: 8
[avision] attach: [55]    gray mode pixel boundary: 8
[avision] attach: [56]    4bit gray mode pixel boundary: 2
[avision] attach: [57]    lineart mode pixel boundary: 8
[avision] attach: [58]    halftone mode pixel boundary: 8
[avision] attach: [59]    error-diffusion mode pixel boundary: 8
[avision] attach: [60]    channels per pixel: 1 3 RESERVED
[avision] attach: [61]    bits per channel: 1 4 8 16
[avision] attach: [62]    scanner type: Flatbed (ADF)
[avision] attach: [75-75] Max shading target : f00
[avision] attach: [77-78] Max X of transparency: 1500 dots * base_dpi
[avision] attach: [79-80] May Y of transparency: 1500 dots * base_dpi
[avision] attach: [81-82] Max X of flatbed:      2552 dots * base_dpi
[avision] attach: [83-84] May Y of flatbed:      4200 dots * base_dpi
[avision] attach: [85-86] Max X of ADF:          2552 dots * base_dpi
[avision] attach: [87-88] May Y of ADF:          4200 dots * base_dpi
[avision] attach: [89-90] Res. in Ex. mode:      2400 dpi
[avision] attach: [91]    ASIC:     6
[avision] attach: [92]    Buttons:  7
[avision] attach: [93]    ESA4: SUPPORTS_ACCESSORIES_DETECT SUPPORTS_ASIC_UPDATE
[avision] attach: [94]    ESA5:
[avision] attach: optical resolution set to: 2400 dpi
[avision] attach: max resolution set to: 2400 dpi
[avision] attach: max channels per pixel: 3, max bits per channel16
[avision] attach: x/y-range for mode 0 is valid!
[avision] attach: Mode 0 range is now: 216.069333 x 355.600000 mm.
[avision] attach: x/y-range for mode 1 is valid!
[avision] attach: Mode 1 range is now: 127.000000 x 127.000000 mm.
[avision] attach: x/y-range for mode 2 is valid!
[avision] attach: Mode 2 range is now: 216.069333 x 355.600000 mm.
[avision] sane_init: parsing config line ""
[avision] sane_init: config file line 16: ignoring empty line
[avision] sane_init: Trying to find USB device 638 a24 ...
[avision] sane_init: Trying to find USB device 638 a23 ...
[avision] sane_init: Trying to find USB device 638 a18 ...
[avision] sane_init: Trying to find USB device 638 a16 ...
[avision] sane_init: Trying to find USB device 638 a13 ...
[avision] sane_init: Trying to find USB device 638 a18 ...
[avision] sane_init: Trying to find USB device 3f0 701 ...
[avision] sane_init: Trying to find USB device 3f0 701 ...
[avision] sane_init: Trying to find USB device 3f0 801 ...
[avision] attach: (Version: 1.0 Build: 92)
[avision] sane_init: Trying to find USB device 638 26a ...
[avision] sane_init: Trying to find USB device 686 4004 ...
[avision] sane_init: Trying to find USB device 638 268 ...
[avision] sane_get_devices:
[avision] sane_open:
[avision] sane_open: using open_extended
[avision] sane_open: got 1048576 scsi_max_request_size
[avision] reserve_unit:
[avision] filling command to have a length of 10, was: 6
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] wait_ready: sending TEST_UNIT_READY
[avision] filling command to have a length of 10, was: 6
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] get_accessories_info
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] get_accessories_info: raw data:
[avision]   [0] 00000000b   0o   0d  0x
[avision]   [1] 00000001b   1o   1d  1x
[avision]   [2] 00000001b   1o   1d  1x
[avision]   [3] 00000000b   0o   0d  0x
[avision]   [4] 00000000b   0o   0d  0x
[avision]   [5] 00000000b   0o   0d  0x
[avision]   [6] 00000000b   0o   0d  0x
[avision]   [7] 00000000b   0o   0d  0x
[avision] get_accessories_info: [0]  ADF: 0
[avision] get_accessories_info: [1]  Light Box: 1
[avision] get_accessories_info: [2]  ADF model: 1 (Oodles)
[avision] add_color_mode: 0 Line Art
[avision] add_color_mode: 1 Dithered
[avision] add_color_mode: 2 Gray
[avision] add_color_mode: 4 16bit Gray
[avision] add_color_mode: 3 Color
[avision] add_color_mode: 5 16bit Color
[avision] init_options:
[avision] init_options: dpi_range.min set to 100 due to device-list!!
[avision] max_string_size:
[avision] make_color_mode:
[avision] make_color_mode: found at 5 mode: 5
[avision] max_string_size:
[avision] make_source_mode: "Normal"
[avision] make_source_mode: found at 0 mode: 0
[avision] sane_control_option:
[avision] sane_control_option:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_control_option:
[avision] sane_get_option_descriptor:
[avision] sane_control_option:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_control_option:
[avision] sane_control_option:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_control_option:
[avision] constrain_value:
[avision] make_source_mode: "Normal"
[avision] make_source_mode: found at 0 mode: 0
[avision] sane_control_option:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_control_option:
[avision] sane_get_option_descriptor:
[avision] sane_control_option:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_control_option:
[avision] sane_control_option:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_control_option:
[avision] constrain_value:
[avision] sane_get_option_descriptor:
[avision] sane_get_option_descriptor:
[avision] sane_control_option:
[avision] constrain_value:
[avision] sane_control_option:
[avision] sane_get_option_descriptor:
[avision] sane_control_option:
[avision] constrain_value:
[avision] sane_control_option:
[avision] sane_get_option_descriptor:
[avision] sane_control_option:
[avision] constrain_value:
[avision] sane_get_parameters:
[avision] sane_get_parameters: boundary 8, gray_mode: 1,
[avision] sane_get_parameters: tlx: 85.000000, tly: 175.000000, brx: 124.999985, bry: 214.999985
[avision] sane_get_parameters: tlx: 1003, tly: 2066, brx: 1476, bry: 2539
[avision] sane_get_parameters: xres: 300, yres: 300, line_difference: 0
[avision] sane_get_parameters: pixel_per_line: 472, lines: 473
[avision] sane_get_parameters: depth: 16, bytes_per_line: 2832
[avision] wait_ready: sending TEST_UNIT_READY
[avision] filling command to have a length of 10, was: 6
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] wait_4_light: getting light status.
[avision] wait_4_light: read bytes 1
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] wait_4_light: command is 0. Result is warming up
[avision] wait_4_light: scanner marked as returning bogus values in device-list!!
[avision] set_window:
[avision] set_window: base_dpi = 1200
[avision] set_window: base paralen: 7
[avision] set_window: final paralen: 20
[avision] set_window: transferlen: 70
[avision] set_window: large data-transfer support (>16bit)!
[avision] window_descriptor:
[avision]   [0] 00000000b   0o   0d  0x
[avision]   [1] 00000000b   0o   0d  0x
[avision]   [2] 00000000b   0o   0d  0x
[avision]   [3] 00000000b   0o   0d  0x
[avision]   [4] 00000000b   0o   0d  0x
[avision]   [5] 00000000b   0o   0d  0x
[avision]   [6] 00000000b   0o   0d  0x
[avision]   [7] 00111110b  76o  62d 3ex
[avision]   [8] 00000000b   0o   0d  0x
[avision]   [9] 00000000b   0o   0d  0x
[avision]   [10] 00000001b   1o   1d  1x
[avision]   [11] 00101100b  54o  44d 2cx
[avision]   [12] 00000001b   1o   1d  1x
[avision]   [13] 00101100b  54o  44d 2cx
[avision]   [14] 00000000b   0o   0d  0x
[avision]   [15] 00000000b   0o   0d  0x
[avision]   [16] 00001111b  17o  15d  fx
[avision]   [17] 10101100b 254o 172d acx
[avision]   [18] 00000000b   0o   0d  0x
[avision]   [19] 00000000b   0o   0d  0x
[avision]   [20] 00100000b  40o  32d 20x
[avision]   [21] 01001000b 110o  72d 48x
[avision]   [22] 00000000b   0o   0d  0x
[avision]   [23] 00000000b   0o   0d  0x
[avision]   [24] 00000111b   7o   7d  7x
[avision]   [25] 01100000b 140o  96d 60x
[avision]   [26] 00000000b   0o   0d  0x
[avision]   [27] 00000000b   0o   0d  0x
[avision]   [28] 00000111b   7o   7d  7x
[avision]   [29] 01100100b 144o 100d 64x
[avision]   [30] 10000000b 200o 128d 80x
[avision]   [31] 10000000b 200o 128d 80x
[avision]   [32] 10000000b 200o 128d 80x
[avision]   [33] 00000101b   5o   5d  5x
[avision]   [34] 00010000b  20o  16d 10x
[avision]   [35] 00000000b   0o   0d  0x
[avision]   [36] 00000000b   0o   0d  0x
[avision]   [37] 00000011b   3o   3d  3x
[avision]   [38] 00000000b   0o   0d  0x
[avision]   [39] 00000000b   0o   0d  0x
[avision]   [40] 00000000b   0o   0d  0x
[avision]   [41] 00000000b   0o   0d  0x
[avision]   [42] 00000000b   0o   0d  0x
[avision]   [43] 00000000b   0o   0d  0x
[avision]   [44] 00000000b   0o   0d  0x
[avision]   [45] 00000000b   0o   0d  0x
[avision]   [46] 00000000b   0o   0d  0x
[avision]   [47] 00000000b   0o   0d  0x
[avision]   [48] 11111111b 377o 255d ffx
[avision]   [49] 00010100b  24o  20d 14x
[avision]   [50] 01100000b 140o  96d 60x
[avision]   [51] 11111111b 377o 255d ffx
[avision]   [52] 00000000b   0o   0d  0x
[avision]   [53] 00001011b  13o  11d  bx
[avision]   [54] 00010000b  20o  16d 10x
[avision]   [55] 00000001b   1o   1d  1x
[avision]   [56] 11011001b 331o 217d d9x
[avision]   [57] 00011000b  30o  24d 18x
[avision]   [58] 00000000b   0o   0d  0x
[avision]   [59] 00000001b   1o   1d  1x
[avision]   [60] 01101101b 155o 109d 6dx
[avision]   [61] 00000001b   1o   1d  1x
[avision]   [62] 01101101b 155o 109d 6dx
[avision]   [63] 00000001b   1o   1d  1x
[avision]   [64] 01101101b 155o 109d 6dx
[avision]   [65] 00000000b   0o   0d  0x
[avision]   [66] 00000000b   0o   0d  0x
[avision]   [67] 00000000b   0o   0d  0x
[avision]   [68] 00000000b   0o   0d  0x
[avision]   [69] 00000000b   0o   0d  0x
[avision]   [70] 00000000b   0o   0d  0x
[avision]   [71] 00000000b   0o   0d  0x
[avision] set_window: [0]     window_id: 0
[avision] set_window: [2-3]   x-axis res: 300
[avision] set_window: [4-5]   y-axis res: 300
[avision] set_window: [6-9]   x-axis upper left: 4012
[avision] set_window: [10-13] y-axis upper left: 8264
[avision] set_window: [14-17] window width: 1888
[avision] set_window: [18-21] window length: 1892
[avision] set_window: [22]    brightness: 128
[avision] set_window: [23]    threshold: 128
[avision] set_window: [24]    contrast: 128
[avision] set_window: [25]    image composition: 5
[avision] set_window: [26]    bits per channel: 16
[avision] set_window: [27-28] halftone pattern: 0
[avision] set_window: [29]    padding_and_bitset: 3
[avision] set_window: [30-31] bit ordering: 0
[avision] set_window: [32]    compression type: 0
[avision] set_window: [33]    compression argument: 0
[avision] set_window: [40]    vendor id: ff
[avision] set_window: [41]    param lenght: 20
[avision] set_window: [42]    bitset1: 60
[avision] set_window: [43]    highlight: 255
[avision] set_window: [44]    shadow: 0
[avision] set_window: [45-46] line-width: 2832
[avision] set_window: [47-48] line-count: 473
[avision] set_window: [49]    bitset2: 18
[avision] set_window: [50]    ir exposure time: 0
[avision] set_window: [51-52] r exposure: 16d
[avision] set_window: [53-54] g exposure: 16d
[avision] set_window: [55-56] b exposure: 16d
[avision] set_window: [57]    bitset3: 0
[avision] set_window: [58]    auto focus: 0
[avision] set_window: [59]    line-widht (MSB): 0
[avision] set_window: [60]    line-count (MSB): 0
[avision] set_window: [61]    edge threshold: 0
[avision] set_window: sending command. Bytes: 70
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] normal_calibration:
[avision] get_calib_format:
[avision] get_calib_format: read_data: 32 bytes
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] debug_print_calib_format:
[avision]   [0] 00000001b   1o   1d  1x
[avision]   [1] 11100000b 340o 224d e0x
[avision]   [2] 00000010b   2o   2d  2x
[avision]   [3] 00111111b  77o  63d 3fx
[avision]   [4] 00000001b   1o   1d  1x
[avision]   [5] 00000001b   1o   1d  1x
[avision]   [6] 00110001b  61o  49d 31x
[avision]   [7] 00110011b  63o  51d 33x
[avision]   [8] 00110110b  66o  54d 36x
[avision]   [9] 00000000b   0o   0d  0x
[avision]   [10] 11110001b 361o 241d f1x
[avision]   [11] 00000000b   0o   0d  0x
[avision]   [12] 11110000b 360o 240d f0x
[avision]   [13] 00000000b   0o   0d  0x
[avision]   [14] 11110010b 362o 242d f2x
[avision]   [15] 00000000b   0o   0d  0x
[avision]   [16] 00000000b   0o   0d  0x
[avision]   [17] 00000000b   0o   0d  0x
[avision]   [18] 00000000b   0o   0d  0x
[avision]   [19] 00000000b   0o   0d  0x
[avision]   [20] 00000000b   0o   0d  0x
[avision]   [21] 00000000b   0o   0d  0x
[avision]   [22] 10011010b 232o 154d 9ax
[avision]   [23] 00110100b  64o  52d 34x
[avision]   [24] 10101100b 254o 172d acx
[avision]   [25] 01100000b 140o  96d 60x
[avision]   [26] 00000000b   0o   0d  0x
[avision]   [27] 00000000b   0o   0d  0x
[avision]   [28] 00000111b   7o   7d  7x
[avision]   [29] 01100100b 144o 100d 64x
[avision]   [30] 10000000b 200o 128d 80x
[avision]   [31] 10000000b 200o 128d 80x
[avision] get_calib_format: [0-1]  pixels per line: 480
[avision] get_calib_format: [2]    bytes per channel: 2
[avision] get_calib_format: [3]    line count: 63
[avision] get_calib_format: [4]    FLAG: MUST_DO_CALIBRATION
[avision] get_calib_format: [5]    Ability1: PACKED NEEDS_CALIB_TABLE_CHANNEL_BY_CHANNEL
[avision] get_calib_format: [6]    R gain: 49
[avision] get_calib_format: [7]    G gain: 51
[avision] get_calib_format: [8]    B gain: 54
[avision] get_calib_format: [9-10] R shading target: f1
[avision] get_calib_format: [11-12] G shading target: f0
[avision] get_calib_format: [13-14] B shading target: f2
[avision] get_calib_format: [15-16] R dark shading target: 0
[avision] get_calib_format: [17-18] G dark shading target: 0
[avision] get_calib_format: [19-20] B dark shading target: 0
[avision] get_calib_format: channels: 3
[avision] normal_calibration: using color calibration
[avision] get_calib_data: type 62, size 60480, line_size: 1048576
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] sort_and_average:
[avision] compute_white_shading_data:
[avision] compute_white_shading_data: target 0: bytes swapped.
[avision] compute_white_shading_data: target 0: f100
[avision] compute_white_shading_data: target 1: bytes swapped.
[avision] compute_white_shading_data: target 1: f100
[avision] compute_white_shading_data: target 2: bytes swapped.
[avision] compute_white_shading_data: target 2: f100
[avision] compute_white_shading_data: 0 invalid, 0 limitted
[avision] set_calib_data:
[avision] set_calib_data: channels in single commands
[avision] set_calib_data_calibration: channel: 0
[avision] set_calib_data: sending 960 bytes now
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] set_calib_data_calibration: channel: 1
[avision] set_calib_data: sending 960 bytes now
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] set_calib_data_calibration: channel: 2
[avision] set_calib_data: sending 960 bytes now
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] send_gamma: table_raw_size: 512, table_size: 512
[avision] send_gamma: values: 2, invert_table: 0
[avision] send_gamma: brightness: 0.000000, contrast: 0.000000
[avision] send_gamma: sending 512 bytes gamma table.
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] send_gamma: sending 512 bytes gamma table.
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] send_gamma: sending 512 bytes gamma table.
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] start_scan:
[avision] start_scan: sending command. Bytes: 6
[avision] filling command to have a length of 10, was: 6
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] sane_get_parameters:
[avision] sane_read: max_len: 32768
[avision] reader_process:
[avision] dev->scsi_buffer_size / 2: 524288, half_inch_bytes: 424800
[avision] bytes_per_line: 2832, pixels_per_line: 2832
[avision] lines_per_stripe: 8, lines_per_output: 8
[avision] max_bytes_per_read: 424800, stripe_size: 22656, out_size: 22656
[avision] reader_process: total_size: 1339536
[avision] reader_process: processed_bytes: 0, total_size: 1339536
[avision] reader_process: this_read: 22656
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 1, status: 0
[avision] reader_process: stripe filled
[avision] reader_process: useful_bytes 22656
[avision] reader_process: end loop
[avision] reader_process: processed_bytes: 22656, total_size: 1339536
[avision] reader_process: this_read: 22656
[avision] sane_read: got 20480 bytes
[avision] sane_read: max_len: 32768
[avision] *** Got error 9 trying to read
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 0, status: 0
[avision] Error during interrupt endpoint status read.
[avision] *** Try to request sense:
[avision] *** Got error 9 trying to request sense!
[avision] reader_process: read_data failed with status: 9
[avision] sane_read: got 0 bytes
[avision] do_eof:
[avision] sane_cancel
[avision] sane_close:

[avision] release unit:
[avision] filling command to have a length of 10, was: 6
[avision] *** Got error 9 trying to write
[avision] avision_usb_status:
[avision] *** (pseudo interrupt) URB  going down ...
[avision] (pseudo interrupt) got: 0, status: 0
[avision] Error during interrupt endpoint status read.
[avision] *** Try to request sense:
[avision] *** Got error 9 trying to request sense!
[avision] sane_exit: