[sane-devel] hp scanjet 8200 avision problem fedora 15 (Problem fixed)

Thu Apr 12 10:51:16 UTC 2012

sane-devel at lists.alioth.debian.org

Re: hp scanjet 8200 avision problem fedora 15 (Problem fixed)

Dear Mike:

I have fixed (worked around) the problem discribed below. I did this by 
running a previous version.
All I can say is that version 1.0.20 works (But is slow, it does lots of 
timeouts) whereas version  1.0.22
does not.

If you like I will run some of the intermediate versions to fined out 
when it went wrong.

below is the output from version 1.0.20

Jonathan

[sanei_debug] Setting debug level of avision to 7.
[avision] sane_init:(Version: 1.0 Build: 290)
[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: 
Rene Rebe <rene at exactcode.de>"
[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 force-a4"
[avision] sane_init: config file line 10: ignoring comment line
[avision] sane_init: parsing config line ""
[avision] sane_init: config file line 11: ignoring empty line
[avision] sane_init: parsing config line "#scsi AVISION"
[avision] sane_init: config file line 12: ignoring comment line
[avision] sane_init: parsing config line "#scsi FCPA"
[avision] sane_init: config file line 13: ignoring comment line
[avision] sane_init: parsing config line "#scsi MINOLTA"
[avision] sane_init: config file line 14: ignoring comment line
[avision] sane_init: parsing config line "#scsi MITSBISH MCA-S600C"
[avision] sane_init: config file line 15: ignoring comment line
[avision] sane_init: parsing config line "#scsi MITSBISH MCA-SS600"
[avision] sane_init: config file line 16: ignoring comment line
[avision] sane_init: parsing config line "#scsi HP"
[avision] sane_init: config file line 17: ignoring comment line
[avision] sane_init: parsing config line "#scsi hp"
[avision] sane_init: config file line 18: ignoring comment line
[avision] sane_init: parsing config line ""
[avision] sane_init: config file line 19: ignoring empty line
[avision] sane_init: parsing config line "#scsi /dev/scanner"
[avision] sane_init: config file line 20: ignoring comment line
[avision] sane_init: parsing config line "# usb libusb:002:003"
[avision] sane_init: config file line 21: ignoring comment line
[avision] sane_init: parsing config line "# usb 0x03f0 0x0701"
[avision] sane_init: config file line 22: ignoring comment line
[avision] sane_init: parsing config line ""
[avision] sane_init: config file line 23: ignoring empty line
[avision] sane_init: Trying to find USB device 638 a27 ...
[avision] sane_init: Trying to find USB device 638 a3c ...
[avision] sane_init: Trying to find USB device 638 a33 ...
[avision] sane_init: Trying to find USB device 638 a93 ...
[avision] sane_init: Trying to find USB device 638 a24 ...
[avision] sane_init: Trying to find USB device 638 a25 ...
[avision] sane_init: Trying to find USB device 638 a3a ...
[avision] sane_init: Trying to find USB device 638 a2f ...
[avision] sane_init: Trying to find USB device 638 a3a ...
[avision] sane_init: Trying to find USB device 638 a23 ...
[avision] sane_init: Trying to find USB device 638 a2a ...
[avision] sane_init: Trying to find USB device 638 a2b ...
[avision] sane_init: Trying to find USB device 638 a2c ...
[avision] sane_init: Trying to find USB device 638 a2d ...
[avision] sane_init: Trying to find USB device 638 a2e ...
[avision] sane_init: Trying to find USB device 638 a94 ...
[avision] sane_init: Trying to find USB device 638 a18 ...
[avision] sane_init: Trying to find USB device 638 a18 ...
[avision] sane_init: Trying to find USB device 638 a5e ...
[avision] sane_init: Trying to find USB device 638 a41 ...
[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 a4e ...
[avision] sane_init: Trying to find USB device 638 a4f ...
[avision] sane_init: Trying to find USB device 638 a65 ...
[avision] sane_init: Trying to find USB device 638 a66 ...
[avision] sane_init: Trying to find USB device 638 a82 ...
[avision] sane_init: Trying to find USB device 638 a84 ...
[avision] sane_init: Trying to find USB device 638 a4d ...
[avision] sane_init: Trying to find USB device 638 a40 ...
[avision] sane_init: Trying to find USB device 638 a68 ...
[avision] sane_init: Trying to find USB device 638 a61 ...
[avision] sane_init: Trying to find USB device 638 aa1 ...
[avision] sane_init: Trying to find USB device 638 a45 ...
[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] sane_init: Trying to find USB device 3f0 b01 ...
[avision] attach:
[avision] attach: opening libusb:001:013
[avision] inquiry: length: 96
[avision] inquiry: inquiring ...
[avision] filling command to have a length of 10, was: 6
[avision] Timeouts: write: 30000, read: 1000, status: 1000
[avision] avision_usb_status: timeout 1000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] attach: Inquiry gives mfg=HP, model=C9930A, product revision=3.20.
[avision] attach: Checking model: 0
[avision] attach: Checking model: 1
[avision] attach: Checking model: 2
[avision] attach: Checking model: 3
[avision] attach: Checking model: 4
[avision] attach: Checking model: 5
[avision] attach: Checking model: 6
[avision] attach: Checking model: 7
[avision] attach: Checking model: 8
[avision] attach: Checking model: 9
[avision] attach: Checking model: 10
[avision] attach: Checking model: 11
[avision] attach: Checking model: 12
[avision] attach: Checking model: 13
[avision] attach: Checking model: 14
[avision] attach: Checking model: 15
[avision] attach: Checking model: 16
[avision] attach: Checking model: 17
[avision] attach: Checking model: 18
[avision] attach: Checking model: 19
[avision] attach: Checking model: 20
[avision] attach: Checking model: 21
[avision] attach: Checking model: 22
[avision] attach: Checking model: 23
[avision] attach: Checking model: 24
[avision] attach: Checking model: 25
[avision] attach: Checking model: 26
[avision] attach: Checking model: 27
[avision] attach: Checking model: 28
[avision] attach: Checking model: 29
[avision] attach: Checking model: 30
[avision] attach: Checking model: 31
[avision] attach: Checking model: 32
[avision] attach: Checking model: 33
[avision] attach: Checking model: 34
[avision] attach: Checking model: 35
[avision] attach: Checking model: 36
[avision] attach: Checking model: 37
[avision] attach: Checking model: 38
[avision] attach: Checking model: 39
[avision] attach: Checking model: 40
[avision] attach: Checking model: 41
[avision] attach: Checking model: 42
[avision] attach: Checking model: 43
[avision] attach: Checking model: 44
[avision] attach: Checking model: 45
[avision] attach: Checking model: 46
[avision] attach: Checking model: 47
[avision] attach: Checking model: 48
[avision] attach: Checking model: 49
[avision] attach: Checking model: 50
[avision] attach: Checking model: 51
[avision] attach: Checking model: 52
[avision] attach: Checking model: 53
[avision] attach: Checking model: 54
[avision] attach: Checking model: 55
[avision] attach: Checking model: 56
[avision] attach: Checking model: 57
[avision] attach: Checking model: 58
[avision] attach: Checking model: 59
[avision] attach: Checking model: 60
[avision] attach: Checking model: 61
[avision] attach: Checking model: 62
[avision] attach: Checking model: 63
[avision] attach: Checking model: 64
[avision] attach: Checking model: 65
[avision] attach: Checking model: 66
[avision] attach: Checking model: 67
[avision] attach: Checking model: 68
[avision] attach: Scanner matched entry: 68: "HP", "C9930A", 0x3f0, 0xb01
[avision] inquiry: length: 96
[avision] inquiry: inquiring ...
[avision] filling command to have a length of 10, was: 6
[avision] Timeouts: write: 30000, read: 1000, status: 1000
[avision] avision_usb_status: timeout 1000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] inquiry: length: 136
[avision] inquiry: inquiring ...
[avision] filling command to have a length of 10, was: 6
[avision] Timeouts: write: 30000, read: 1000, status: 1000
[avision] avision_usb_status: timeout 1000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) 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] 01001000b 110o  72d 48x
[avision]   [9] 01010000b 120o  80d 50x
[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] 01000011b 103o  67d 43x
[avision]   [17] 00111001b  71o  57d 39x
[avision]   [18] 00111001b  71o  57d 39x
[avision]   [19] 00110011b  63o  51d 33x
[avision]   [20] 00110000b  60o  48d 30x
[avision]   [21] 01000001b 101o  65d 41x
[avision]   [22] 00100000b  40o  32d 20x
[avision]   [23] 00100000b  40o  32d 20x
[avision]   [24] 00100000b  40o  32d 20x
[avision]   [25] 00100000b  40o  32d 20x
[avision]   [26] 00100000b  40o  32d 20x
[avision]   [27] 00100000b  40o  32d 20x
[avision]   [28] 00100000b  40o  32d 20x
[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] 00110000b  60o  48d 30x
[avision]   [35] 00110001b  61o  49d 31x
[avision]   [36] 10100001b 241o 161d a1x
[avision]   [37] 00110000b  60o  48d 30x
[avision]   [38] 00110000b  60o  48d 30x
[avision]   [39] 01100111b 147o 103d 67x
[avision]   [40] 00010010b  22o  18d 12x
[avision]   [41] 11000000b 300o 192d c0x
[avision]   [42] 00010010b  22o  18d 12x
[avision]   [43] 11000000b 300o 192d c0x
[avision]   [44] 00010010b  22o  18d 12x
[avision]   [45] 11000000b 300o 192d c0x
[avision]   [46] 00010010b  22o  18d 12x
[avision]   [47] 11000000b 300o 192d c0x
[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] 00000010b   2o   2d  2x
[avision]   [78] 00001000b  10o   8d  8x
[avision]   [79] 00000111b   7o   7d  7x
[avision]   [80] 00111110b  76o  62d 3ex
[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] 00010010b  22o  18d 12x
[avision]   [90] 11000000b 300o 192d c0x
[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]   [96] 00000000b   0o   0d  0x
[avision]   [97] 00000000b   0o   0d  0x
[avision]   [98] 00000000b   0o   0d  0x
[avision]   [99] 00000000b   0o   0d  0x
[avision]   [100] 00000000b   0o   0d  0x
[avision]   [101] 00000000b   0o   0d  0x
[avision]   [102] 00000000b   0o   0d  0x
[avision]   [103] 00000000b   0o   0d  0x
[avision]   [104] 00000000b   0o   0d  0x
[avision]   [105] 00000000b   0o   0d  0x
[avision]   [106] 00000000b   0o   0d  0x
[avision]   [107] 00000000b   0o   0d  0x
[avision]   [108] 00000000b   0o   0d  0x
[avision]   [109] 00000000b   0o   0d  0x
[avision]   [110] 00000000b   0o   0d  0x
[avision]   [111] 00000000b   0o   0d  0x
[avision]   [112] 00000000b   0o   0d  0x
[avision]   [113] 00000000b   0o   0d  0x
[avision]   [114] 00000000b   0o   0d  0x
[avision]   [115] 00000000b   0o   0d  0x
[avision]   [116] 00000000b   0o   0d  0x
[avision]   [117] 00000000b   0o   0d  0x
[avision]   [118] 00000000b   0o   0d  0x
[avision]   [119] 00000000b   0o   0d  0x
[avision]   [120] 00000000b   0o   0d  0x
[avision]   [121] 00000000b   0o   0d  0x
[avision]   [122] 00000000b   0o   0d  0x
[avision]   [123] 00000000b   0o   0d  0x
[avision]   [124] 00000000b   0o   0d  0x
[avision]   [125] 00000000b   0o   0d  0x
[avision]   [126] 00000000b   0o   0d  0x
[avision]   [127] 00000000b   0o   0d  0x
[avision]   [128] 00000000b   0o   0d  0x
[avision]   [129] 00000000b   0o   0d  0x
[avision]   [130] 00000000b   0o   0d  0x
[avision]   [131] 00000000b   0o   0d  0x
[avision]   [132] 00000000b   0o   0d  0x
[avision]   [133] 00000000b   0o   0d  0x
[avision]   [134] 00000000b   0o   0d  0x
[avision]   [135] 00000000b   0o   0d  0x
[avision] attach: [8-15]  Vendor id.:      'HP      '
[avision] attach: [16-31] Product id.:     'C9930A          '
[avision] attach: [32-35] Product rev.:    '0.01'
[avision] attach: [36]    Bitfield: ADF 3-pass color BGR color plane
[avision] attach: [37]    Optical res.:    4800 dpi
[avision] attach: [38]    Maximum res.:    4800 dpi
[avision] attach: [39]    Bitfield1: Q_SCAN EXTENDED_RES NEW_PROTOCOL 
AVISION
[avision] attach: [40-41] X res. in gray:  4800 dpi
[avision] attach: [42-43] Y res. in gray:  4800 dpi
[avision] attach: [44-45] X res. in color: 4800 dpi
[avision] attach: [46-47] Y res. in color: 4800 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
[avision] attach: [61]    bits per channel: 1 4 8 16
[avision] attach: [62]    scanner type: Flatbed (ADF)
[avision] attach: [75-76] Max shading target : f00
[avision] attach: [77-78] Max X of transparency: 520 dots * base_dpi
[avision] attach: [79-80] Max Y of transparency: 1854 dots * base_dpi
[avision] attach: [81-82] Max X of flatbed:      2552 dots * base_dpi
[avision] attach: [83-84] Max Y of flatbed:      4200 dots * base_dpi
[avision] attach: [85-86] Max X of ADF:          2552 dots * base_dpi
[avision] attach: [87-88] Max Y of ADF:          4200 dots * base_dpi
[avision] attach: [89-90] Res. in Ex. mode:      4800 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: [95]    ESA6:
[avision] attach: [128]    ESA7:
[avision] attach: [129]    YCbCr:
[avision] attach: optical resolution set to: 4800 dpi
[avision] attach: max resolution set to: 4800 dpi
[avision] attach: max channels per pixel: 3, max bits per channel: 16
[avision] attach: x/y-range for mode 0 is valid!
[avision] attach: Mode 0 range is now: 216.169333 x 355.600000 mm.
[avision] attach: x/y-range for mode 1 is valid!
[avision] attach: Mode 1 range is now: 44.126667 x 156.972000 mm.
[avision] attach: x/y-range for mode 2 is valid!
[avision] attach: Mode 2 range is now: 216.169333 x 355.600000 mm.
[avision] sane_init: Trying to find USB device 3f0 3905 ...
[avision] sane_init: Trying to find USB device 3f0 3805 ...
[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 686 400d ...
[avision] sane_init: Trying to find USB device 686 400e ...
[avision] sane_init: Trying to find USB device 638 a15 ...
[avision] sane_init: Trying to find USB device 638 a16 ...
[avision] sane_init: Trying to find USB device 4c5 1029 ...
[avision] sane_init: Trying to find USB device 40a 6001 ...
[avision] sane_init: Trying to find USB device 40a 6002 ...
[avision] sane_init: Trying to find USB device 40a 6003 ...
[avision] sane_init: Trying to find USB device 40a 6003 ...
[avision] sane_init: Trying to find USB device 40a 6004 ...
[avision] sane_init: Trying to find USB device 40a 6004 ...
[avision] sane_init: Trying to find USB device 40a 6005 ...
[avision] sane_init: Trying to find USB device 638 268 ...
[avision] sane_init: Trying to find USB device 4a7 424 ...
[avision] sane_init: Trying to find USB device 4a7 491 ...
[avision] sane_init: Trying to find USB device 4a7 479 ...
[avision] sane_init: Trying to find USB device 4a7 48f ...
[avision] sane_init: Trying to find USB device 4a7 420 ...
[avision] sane_init: Trying to find USB device 4a7 421 ...
[avision] sane_init: Trying to find USB device 4a7 47a ...
[avision] sane_init: Trying to find USB device 4a7 422 ...
[avision] sane_init: Trying to find USB device 4a7 390 ...
[avision] sane_init: Trying to find USB device 4a7 47b ...
[avision] sane_init: Trying to find USB device 4a7 423 ...
[avision] sane_init: Trying to find USB device 4a7 493 ...
[avision] sane_init: Trying to find USB device 4a7 497 ...
[avision] sane_init: Trying to find USB device 4a7 48f ...
[avision] sane_init: Trying to find USB device 4a7 498 ...
[avision] sane_init: Trying to find USB device 4a7 499 ...
[avision] sane_init: Trying to find USB device 4a7 49c ...
[avision] sane_init: Trying to find USB device 4a7 477 ...
[avision] sane_init: Trying to find USB device 4a7 49d ...
[avision] sane_init: Trying to find USB device 4a7 448 ...
[avision] sane_init: Trying to find USB device 4a7 490 ...
[avision] sane_init: Trying to find USB device 4a7 449 ...
[avision] sane_init: Trying to find USB device 4a7 48c ...
[avision] sane_init: Trying to find USB device 4a7 44c ...
[avision] sane_init: Trying to find USB device 4a7 48d ...
[avision] sane_init: Trying to find USB device 4a7 475 ...
[avision] sane_init: Trying to find USB device 4a7 48e ...
[avision] sane_init: Trying to find USB device 4a7 446 ...
[avision] sane_init: Trying to find USB device 4a7 495 ...
[avision] sane_init: Trying to find USB device 4a7 47c ...
[avision] sane_init: Trying to find USB device 4a7 447 ...
[avision] sane_init: Trying to find USB device 4a7 492 ...
[avision] sane_init: Trying to find USB device 4a7 498 ...
[avision] sane_init: Trying to find USB device 4a7 478 ...
[avision] sane_init: Trying to find USB device 4a7 49a ...
[avision] sane_init: Trying to find USB device 638 a16 ...
[avision] sane_init: Trying to find USB device 482 335 ...
[avision] sane_get_devices:
[avision] sane_open:
[avision] attach:
[avision] sane_open: using open_extended
[avision] sane_open: got 1048576 scsi_max_request_size
[avision] inquiry: length: 96
[avision] inquiry: inquiring ...
[avision] filling command to have a length of 10, was: 6
[avision] Timeouts: write: 30000, read: 1000, status: 1000
[avision] avision_usb_status: timeout 1000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] wait_ready: sending TEST_UNIT_READY
[avision] filling command to have a length of 10, was: 6
[avision] Timeouts: write: 30000, read: 15000, status: 15000
[avision] avision_usb_status: timeout 15000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] get_accessories_info
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] get_accessories_info: raw data:
[avision]   [0] 00000000b   0o   0d  0x
[avision]   [1] 00000000b   0o   0d  0x
[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: 0
[avision] get_accessories_info: [2]  ADF model: 1 (Oodles)
[avision] add_color_mode: 0 Lineart
[avision] add_color_mode: 1 Dithered
[avision] add_color_mode: 2 Gray
[avision] add_color_mode: 4 16bit Gray
[avision] add_color_mode: 5 Color
[avision] add_color_mode: 7 16bit Color
[avision] init_options:
[avision] init_options: dpi_range.min set to 50
[avision] max_string_size:
[avision] match_color_mode:
[avision] match_color_mode: found at 4 mode: 5
[avision] max_string_size:
[avision] match_source_mode: "Normal"
[avision] match_source_mode: found at 0 mode: 0
[avision] match_source_mode_dim: 0
[avision] sane_get_option_descriptor:
[avision] sane_control_option: option=0, action=0
[avision] sane_control_option: option=, action=GET
[avision] sane_get_option_descriptor:
[avision] sane_control_option: option=0, action=0
[avision] sane_control_option: option=, action=GET
[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_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_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: option=10, action=0
[avision] sane_control_option: option=br-x, action=GET
[avision] sane_control_option: option=8, action=0
[avision] sane_control_option: option=tl-x, action=GET
[avision] sane_control_option: option=11, action=0
[avision] sane_control_option: option=br-y, action=GET
[avision] sane_control_option: option=9, action=0
[avision] sane_control_option: option=tl-y, action=GET
[avision] sane_control_option: option=8, action=0
[avision] sane_control_option: option=tl-x, action=GET
[avision] sane_get_option_descriptor:
[avision] sane_control_option: option=10, action=1
[avision] sane_control_option: option=br-x, action=SET
[avision] constrain_value:
[avision] sane_control_option: option=9, action=0
[avision] sane_control_option: option=tl-y, action=GET
[avision] sane_get_option_descriptor:
[avision] sane_control_option: option=11, action=1
[avision] sane_control_option: option=br-y, action=SET
[avision] constrain_value:
[avision] sane_start:
[avision] sane_get_parameters:
[avision] sane_get_parameters: computing parameters
[avision] sane_compute_parameters:
[avision] sane_compute_parameters: boundary 8, gray_mode: 1,
[avision] sane_compute_parameters: tlx: 0.000000, tly: 0.000000, brx: 
216.000000, bry: 355.000000
[avision] sane_compute_parameters: hw_xres: 150, hw_yres: 150, 
line_difference: 0
[avision] sane_compute_parameters: tlx: 0, tly: 0, brx: 1275, bry: 2096
[avision] sane_compute_parameters: hw_pixel_per_line: 1272, hw_lines: 
2096, hw_bytes_per_line: 0
[avision] sane_compute_parameters: xres: 150, yres: 150
[avision] sane_compute_parameters: pixel_per_line: 1272, lines: 2096
[avision] sane_compute_parameters: depth: 8, bytes_per_line: 3816
[avision] sane_compute_parameters:
[avision] sane_compute_parameters: boundary 8, gray_mode: 1,
[avision] sane_compute_parameters: tlx: 0.000000, tly: 0.000000, brx: 
216.000000, bry: 355.000000
[avision] sane_compute_parameters: hw_xres: 150, hw_yres: 150, 
line_difference: 0
[avision] sane_compute_parameters: tlx: 0, tly: 0, brx: 1275, bry: 2096
[avision] sane_compute_parameters: hw_pixel_per_line: 1272, hw_lines: 
2096, hw_bytes_per_line: 3816
[avision] sane_compute_parameters: xres: 150, yres: 150
[avision] sane_compute_parameters: pixel_per_line: 1272, lines: 2096
[avision] sane_compute_parameters: depth: 8, bytes_per_line: 3816
[avision] wait_4_light: getting light status.
[avision] wait_4_light: read bytes 1
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] wait_4_light: command is 0. Result is on
[avision] set_window:
[avision] set_window: base_dpi_abs: 1200, base_dpi_rel: 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] set_window: source mode 0 source mode dim 0
[avision] window_data_header:
[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] window_descriptor:
[avision]   [0] 00000000b   0o   0d  0x
[avision]   [1] 00000000b   0o   0d  0x
[avision]   [2] 00000000b   0o   0d  0x
[avision]   [3] 10010110b 226o 150d 96x
[avision]   [4] 00000000b   0o   0d  0x
[avision]   [5] 10010110b 226o 150d 96x
[avision]   [6] 00000000b   0o   0d  0x
[avision]   [7] 00000000b   0o   0d  0x
[avision]   [8] 00000000b   0o   0d  0x
[avision]   [9] 00000000b   0o   0d  0x
[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] 00100111b  47o  39d 27x
[avision]   [17] 11000001b 301o 193d c1x
[avision]   [18] 00000000b   0o   0d  0x
[avision]   [19] 00000000b   0o   0d  0x
[avision]   [20] 01000001b 101o  65d 41x
[avision]   [21] 10000001b 201o 129d 81x
[avision]   [22] 10000000b 200o 128d 80x
[avision]   [23] 10000000b 200o 128d 80x
[avision]   [24] 10000000b 200o 128d 80x
[avision]   [25] 00000101b   5o   5d  5x
[avision]   [26] 00001000b  10o   8d  8x
[avision]   [27] 00000000b   0o   0d  0x
[avision]   [28] 00000000b   0o   0d  0x
[avision]   [29] 00000011b   3o   3d  3x
[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]   [40] 11111111b 377o 255d ffx
[avision]   [41] 00010100b  24o  20d 14x
[avision]   [42] 01100000b 140o  96d 60x
[avision]   [43] 11111111b 377o 255d ffx
[avision]   [44] 00000000b   0o   0d  0x
[avision]   [45] 00001110b  16o  14d  ex
[avision]   [46] 11101000b 350o 232d e8x
[avision]   [47] 00001000b  10o   8d  8x
[avision]   [48] 00110000b  60o  48d 30x
[avision]   [49] 00010000b  20o  16d 10x
[avision]   [50] 00000000b   0o   0d  0x
[avision]   [51] 00000000b   0o   0d  0x
[avision]   [52] 00000000b   0o   0d  0x
[avision]   [53] 00000000b   0o   0d  0x
[avision]   [54] 00000000b   0o   0d  0x
[avision]   [55] 00000000b   0o   0d  0x
[avision]   [56] 00000000b   0o   0d  0x
[avision]   [57] 00000000b   0o   0d  0x
[avision]   [58] 00000000b   0o   0d  0x
[avision]   [59] 00000000b   0o   0d  0x
[avision]   [60] 00000000b   0o   0d  0x
[avision]   [61] 00000000b   0o   0d  0x
[avision]   [62] 00000000b   0o   0d  0x
[avision]   [63] 00000000b   0o   0d  0x
[avision] set_window: [0]     window_id: 0
[avision] set_window: [2-3]   x-axis res: 150
[avision] set_window: [4-5]   y-axis res: 150
[avision] set_window: [6-9]   x-axis upper left: 0
[avision] set_window: [10-13] y-axis upper left: 0
[avision] set_window: [14-17] window width: 10177
[avision] set_window: [18-21] window length: 16769
[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: 8
[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: [34-35] paper length: 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: 3816
[avision] set_window: [47-48] line-count: 2096
[avision] set_window: [49]    bitset2: 10
[avision] set_window: [50]    ir exposure time: 0
[avision] set_window: [51-52] r exposure: 0
[avision] set_window: [53-54] g exposure: 0
[avision] set_window: [55-56] b exposure: 0
[avision] set_window: [57]    bitset3: 0
[avision] set_window: [58]    auto focus: 0
[avision] set_window: [59]    line-width (MSB): 0
[avision] set_window: [60]    line-count (MSB): 0
[avision] set_window: [61]    background lines: 0
[avision] set_window: sending command. Bytes: 70
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] normal_calibration:
[avision] get_calib_format:
[avision] get_calib_format: read_data: 32 bytes
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] debug_print_calib_format:
[avision]   [0] 00000110b   6o   6d  6x
[avision]   [1] 10101000b 250o 168d a8x
[avision]   [2] 00000001b   1o   1d  1x
[avision]   [3] 00111111b  77o  63d 3fx
[avision]   [4] 00000001b   1o   1d  1x
[avision]   [5] 00000001b   1o   1d  1x
[avision]   [6] 00101010b  52o  42d 2ax
[avision]   [7] 00110001b  61o  49d 31x
[avision]   [8] 01011000b 130o  88d 58x
[avision]   [9] 00000000b   0o   0d  0x
[avision]   [10] 11100000b 340o 224d e0x
[avision]   [11] 00000000b   0o   0d  0x
[avision]   [12] 11100000b 340o 224d e0x
[avision]   [13] 00000000b   0o   0d  0x
[avision]   [14] 11100000b 340o 224d e0x
[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] 10000000b 200o 128d 80x
[avision]   [23] 01110000b 160o 112d 70x
[avision]   [24] 10101101b 255o 173d adx
[avision]   [25] 11000001b 301o 193d c1x
[avision]   [26] 00000000b   0o   0d  0x
[avision]   [27] 00000000b   0o   0d  0x
[avision]   [28] 01000001b 101o  65d 41x
[avision]   [29] 10000001b 201o 129d 81x
[avision]   [30] 10000000b 200o 128d 80x
[avision]   [31] 10000000b 200o 128d 80x
[avision] get_calib_format: [0-1]  pixels per line: 1704
[avision] get_calib_format: [2]    bytes per channel: 1
[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: 42
[avision] get_calib_format: [7]    G gain: 49
[avision] get_calib_format: [8]    B gain: 88
[avision] get_calib_format: [9-10] R shading target: e0
[avision] get_calib_format: [11-12] G shading target: e0
[avision] get_calib_format: [13-14] B shading target: e0
[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: [21]    true-gray gain: 0
[avision] get_calib_format: [22-23] true-gray shading target: 8070
[avision] get_calib_format: [24-25] true-gray dark shading target: adc1
[avision] get_calib_format: channels: 3
[avision] normal_calibration: using color calibration
[avision] get_calib_data: type 62, size 107352, chunk_size: 107352
[avision] get_calib_data: Reading 107352 bytes calibration data
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] get_calib_data: Got 107352 bytes calibration data
[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: e000
[avision] compute_white_shading_data: target 1: bytes swapped.
[avision] compute_white_shading_data: target 1: e000
[avision] compute_white_shading_data: target 2: bytes swapped.
[avision] compute_white_shading_data: target 2: e000
[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 3408 bytes now
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] set_calib_data_calibration: channel: 1
[avision] set_calib_data: sending 3408 bytes now
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] set_calib_data_calibration: channel: 2
[avision] set_calib_data: sending 3408 bytes now
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] send_3x3_matrix:
[avision] send_3x3_matrix: sending matrix split into two commands
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) 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] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] send_gamma: sending 512 bytes gamma table.
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] send_gamma: sending 512 bytes gamma table.
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] sane_start: starting thread
[avision] sane_get_parameters:
[avision] reader_process:
[avision] reserve_unit:
[avision] filling command to have a length of 10, was: 6
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) 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] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] dev->scsi_buffer_size / 2: 524288
[avision] bytes_per_line: 3816, pixels_per_line: 1272
[avision] lines_per_stripe: 32, lines_per_output: 32
[avision] max_bytes_per_read: 1048576, stripe_size: 122112, out_size: 122112
[avision] reader_process: total_size: 7998336
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 0, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 122112, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 244224, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 366336, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 488448, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 610560, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 732672, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 854784, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 976896, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 1099008, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 1221120, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 1343232, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 1465344, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 1587456, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 1709568, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 1831680, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 1953792, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 2075904, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 2198016, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 2320128, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 2442240, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 2564352, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 2686464, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 2808576, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 2930688, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 3052800, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 3174912, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 3297024, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 3419136, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 3541248, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 3663360, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 3785472, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 3907584, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 4029696, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 4151808, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 4273920, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 4396032, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 4518144, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 4640256, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 4762368, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 4884480, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 5006592, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 5128704, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 5250816, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 5372928, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 5495040, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 5617152, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 5739264, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 5861376, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 5983488, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 6105600, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 6227712, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 6349824, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 6471936, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 6594048, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 6716160, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 6838272, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 6960384, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 7082496, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 7204608, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 7326720, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 7448832, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 7570944, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 7693056, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 7815168, total_size: 7998336
[avision] reader_process: this_read: 122112
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 122112
[avision] reader_process: useful_bytes 122112
[avision] reader_process: end of iteration
[avision] reader_process: stripe filled: 0
[avision] reader_process: processed_bytes: 7937280, total_size: 7998336
[avision] reader_process: this_read: 61056
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] reader_process: stripe filled: 61056
[avision] reader_process: useful_bytes 61056
[avision] reader_process: end of iteration
[avision] reader_process: i/o loop finished
[avision] release unit: type: 0
[avision] filling command to have a length of 10, was: 6
[avision] Timeouts: write: 30000, read: 30000, status: 10000
[avision] avision_usb_status: timeout 10000, 1 retries
[avision] ==> (bulk read) going down ...
[avision] <== (bulk read) got: 1, status: 0
[avision] sane_read: got 0 bytes, err: 0 Success
[avision] do_eof:
[avision] reader_process: returning success
[avision] do_eof: returning 5
[avision] sane_cancel:
[avision] do_cancel:
[avision] sane_close:
[avision] sane_exit:

Hi Jonathan,

This is fixed in the source code repository.  Please compile the latest
version and let me know if you are still having problems.

Mike
(:

On Fri, Mar 30, 2012 at 04:10:02PM +0100, Jonathan Gratus wrote:
 > Dear Sane
 >
 > When I was running  a previous version of fedora (I think it was 11)
 > I could use my hp scanjet 8200
 >
 > I am running fedora 15 an I can no longer run it.
 >
 > scanimage -L produces
 >
 > device `avision:libusb:001:007' is a Hewlett-Packard ScanJet 8200
 > flatbed scanner
 >
 > when I run scanimage it produces
 >
 > I get the following error
 >
 > scanimage: open of device avision:libusb:001:007 failed: Operation
 > not supported
 >
 > It is set SANE_DEBUG_AVISION=7
 >
 > I get the output below
 >
 > Any help will be appreciated
 >
 > Jonathan
 >
 > --------------------------------------
 >
 > [sanei_debug] Setting debug level of avision to 7.
 > [avision] sane_init:(Version: 1.0 Build: 294)
 > [avision] sane_get_devices:
 > [avision] sane_reload_devices: parsing config line ""
 > [avision] sane_reload_devices: config file line 1: ignoring empty line
 > [avision] sane_reload_devices: parsing config line "# These are the
 > possible options. Normally any scanner"
 > [avision] sane_reload_devices: config file line 2: ignoring comment line
 > [avision] sane_reload_devices: parsing config line "# should work
 > just fine without them - and they are only"
 > [avision] sane_reload_devices: config file line 3: ignoring comment line
 > [avision] sane_reload_devices: parsing config line "# needed for
 > test and debugging. So if you experience problems"
 > [avision] sane_reload_devices: config file line 4: ignoring comment line
 > [avision] sane_reload_devices: parsing config line "# and you solve
 > them with enabling options here, please notify"
 > [avision] sane_reload_devices: config file line 5: ignoring comment line
 > [avision] sane_reload_devices: parsing config line "# the
 > SANE/Avision maintainer: Mike Kelly <mike at piratehaven.org>"
 > [avision] sane_reload_devices: config file line 6: ignoring comment line
 > [avision] sane_reload_devices: parsing config line ""
 > [avision] sane_reload_devices: config file line 7: ignoring empty line
 > [avision] sane_reload_devices: parsing config line "option
 > disable-gamma-table"
 > [avision] sane_reload_devices: config file line 8: disable-gamma-table
 > [avision] sane_reload_devices: parsing config line "option
 > disable-calibration"
 > [avision] sane_reload_devices: config file line 9: disable-calibration
 > [avision] sane_reload_devices: parsing config line "#option force-a4"
 > [avision] sane_reload_devices: config file line 10: ignoring comment line
 > [avision] sane_reload_devices: parsing config line ""
 > [avision] sane_reload_devices: config file line 11: ignoring empty line
 > [avision] sane_reload_devices: parsing config line "#scsi AVISION"
 > [avision] sane_reload_devices: config file line 12: ignoring comment line
 > [avision] sane_reload_devices: parsing config line "#scsi FCPA"
 > [avision] sane_reload_devices: config file line 13: ignoring comment line
 > [avision] sane_reload_devices: parsing config line "#scsi MINOLTA"
 > [avision] sane_reload_devices: config file line 14: ignoring comment line
 > [avision] sane_reload_devices: parsing config line "#scsi MITSBISH
 > MCA-S600C"
 > [avision] sane_reload_devices: config file line 15: ignoring comment line
 > [avision] sane_reload_devices: parsing config line "#scsi MITSBISH
 > MCA-SS600"
 > [avision] sane_reload_devices: config file line 16: ignoring comment line
 > [avision] sane_reload_devices: parsing config line "scsi HP"
 > [avision] sane_reload_devices: config file line 17: trying to attach
 > SCSI: scsi HP'
 > [avision] sane_reload_devices: parsing config line "scsi hp"
 > [avision] sane_reload_devices: config file line 18: trying to attach
 > SCSI: scsi hp'
 > [avision] sane_reload_devices: parsing config line ""
 > [avision] sane_reload_devices: config file line 19: ignoring empty line
 > [avision] sane_reload_devices: parsing config line "#scsi /dev/scanner"
 > [avision] sane_reload_devices: config file line 20: ignoring comment line
 > [avision] sane_reload_devices: parsing config line "# usb libusb:002:003"
 > [avision] sane_reload_devices: config file line 21: ignoring comment line
 > [avision] sane_reload_devices: parsing config line "# usb 0x03f0 0x0701"
 > [avision] sane_reload_devices: config file line 22: ignoring comment line
 > [avision] sane_reload_devices: parsing config line ""
 > [avision] sane_reload_devices: config file line 23: ignoring empty line
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a27 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a3c ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a33 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a93 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a24 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a25 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a3a ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a2f ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 1a35 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a23 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a2a ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a2b ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a2c ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a2d ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a2e ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a94 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a19 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a18 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a5e ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a41 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a16 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a13 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a4e ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a4f ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a65 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a66 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a82 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a84 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a4d ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a40 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a68 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a61 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0aa1 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a45 ...
 > [avision] sane_reload_devices: Trying to find USB device 03f0 0701 ...
 > [avision] sane_reload_devices: Trying to find USB device 03f0 0701 ...
 > [avision] sane_reload_devices: Trying to find USB device 03f0 0801 ...
 > [avision] sane_reload_devices: Trying to find USB device 03f0 0b01 ...
 > [avision] attach:
 > [avision] attach: opening libusb:001:007
 > [avision] inquiry: length: 96
 > [avision] inquiry: inquiring ...
 > [avision] filling command to have a length of 10, was: 6
 > [avision] Timeouts: write: 30000, read: 1000, status: 1000
 > [avision] avision_usb_status: timeout 1000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] attach: Inquiry gives mfg=HP, model=C9930A, product 
revision=3.20.
 > [avision] attach: Checking model: 0
 > [avision] attach: Checking model: 1
 > [avision] attach: Checking model: 2
 > [avision] attach: Checking model: 3
 > [avision] attach: Checking model: 4
 > [avision] attach: Checking model: 5
 > [avision] attach: Checking model: 6
 > [avision] attach: Checking model: 7
 > [avision] attach: Checking model: 8
 > [avision] attach: Checking model: 9
 > [avision] attach: Checking model: 10
 > [avision] attach: Checking model: 11
 > [avision] attach: Checking model: 12
 > [avision] attach: Checking model: 13
 > [avision] attach: Checking model: 14
 > [avision] attach: Checking model: 15
 > [avision] attach: Checking model: 16
 > [avision] attach: Checking model: 17
 > [avision] attach: Checking model: 18
 > [avision] attach: Checking model: 19
 > [avision] attach: Checking model: 20
 > [avision] attach: Checking model: 21
 > [avision] attach: Checking model: 22
 > [avision] attach: Checking model: 23
 > [avision] attach: Checking model: 24
 > [avision] attach: Checking model: 25
 > [avision] attach: Checking model: 26
 > [avision] attach: Checking model: 27
 > [avision] attach: Checking model: 28
 > [avision] attach: Checking model: 29
 > [avision] attach: Checking model: 30
 > [avision] attach: Checking model: 31
 > [avision] attach: Checking model: 32
 > [avision] attach: Checking model: 33
 > [avision] attach: Checking model: 34
 > [avision] attach: Checking model: 35
 > [avision] attach: Checking model: 36
 > [avision] attach: Checking model: 37
 > [avision] attach: Checking model: 38
 > [avision] attach: Checking model: 39
 > [avision] attach: Checking model: 40
 > [avision] attach: Checking model: 41
 > [avision] attach: Checking model: 42
 > [avision] attach: Checking model: 43
 > [avision] attach: Checking model: 44
 > [avision] attach: Checking model: 45
 > [avision] attach: Checking model: 46
 > [avision] attach: Checking model: 47
 > [avision] attach: Checking model: 48
 > [avision] attach: Checking model: 49
 > [avision] attach: Checking model: 50
 > [avision] attach: Checking model: 51
 > [avision] attach: Checking model: 52
 > [avision] attach: Checking model: 53
 > [avision] attach: Checking model: 54
 > [avision] attach: Checking model: 55
 > [avision] attach: Checking model: 56
 > [avision] attach: Checking model: 57
 > [avision] attach: Checking model: 58
 > [avision] attach: Checking model: 59
 > [avision] attach: Checking model: 60
 > [avision] attach: Checking model: 61
 > [avision] attach: Checking model: 62
 > [avision] attach: Checking model: 63
 > [avision] attach: Checking model: 64
 > [avision] attach: Checking model: 65
 > [avision] attach: Checking model: 66
 > [avision] attach: Checking model: 67
 > [avision] attach: Checking model: 68
 > [avision] attach: Scanner matched entry: 68: "HP", "C9930A", 0x03f0, 
0x0b01
 > [avision] attach: reading firmware status
 > [avision] get_firmware_status
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] get_firmware_status: raw data:
 > [avision]   [0] 00000000b   0o   0d  0x
 > [avision]   [1] 00000000b   0o   0d  0x
 > [avision]   [2] 01110000b 160o 112d 70x
 > [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_firmware_status: [0]  needs firmware 0
 > [avision] get_firmware_status: [1]  side edge: 112
 > [avision] get_firmware_status: [3]  side edge: 0
 > [avision] get_firmware_status: [5]  side edge: 0
 > [avision] attach: reading flash ram info
 > [avision] get_flash_ram_info
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] get_flash_ram_info: raw data:
 > [avision]   [0] 00000001b   1o   1d  1x
 > [avision]   [1] 00001110b  16o  14d  ex
 > [avision]   [2] 00000000b   0o   0d  0x
 > [avision]   [3] 00000000b   0o   0d  0x
 > [avision]   [4] 00000000b   0o   0d  0x
 > [avision]   [5] 00000101b   5o   5d  5x
 > [avision]   [6] 00000000b   0o   0d  0x
 > [avision]   [7] 00000000b   0o   0d  0x
 > [avision]   [8] 00000000b   0o   0d  0x
 > [avision]   [9] 00001111b  17o  15d  fx
 > [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] 00000000b   0o   0d  0x
 > [avision]   [17] 00000001b   1o   1d  1x
 > [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) RAWLINE(r)
 > FIRMWARE(r/w)
 > [avision] get_flash_ram_info: [2-5]   size CTAB: 5
 > [avision] get_flash_ram_info: [6-9]   size FIRMWARE: 15
 > [avision] get_flash_ram_info: [10-13] size RAWLINE: 0
 > [avision] get_flash_ram_info: [14-17] size FMDBG: 1
 > [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] inquiry: length: 96
 > [avision] inquiry: inquiring ...
 > [avision] filling command to have a length of 10, was: 6
 > [avision] Timeouts: write: 30000, read: 1000, status: 1000
 > [avision] avision_usb_status: timeout 1000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] inquiry: length: 136
 > [avision] inquiry: inquiring ...
 > [avision] filling command to have a length of 10, was: 6
 > [avision] Timeouts: write: 30000, read: 1000, status: 1000
 > [avision] avision_usb_status: timeout 1000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) 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] 01001000b 110o  72d 48x
 > [avision]   [9] 01010000b 120o  80d 50x
 > [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] 01000011b 103o  67d 43x
 > [avision]   [17] 00111001b  71o  57d 39x
 > [avision]   [18] 00111001b  71o  57d 39x
 > [avision]   [19] 00110011b  63o  51d 33x
 > [avision]   [20] 00110000b  60o  48d 30x
 > [avision]   [21] 01000001b 101o  65d 41x
 > [avision]   [22] 00100000b  40o  32d 20x
 > [avision]   [23] 00100000b  40o  32d 20x
 > [avision]   [24] 00100000b  40o  32d 20x
 > [avision]   [25] 00100000b  40o  32d 20x
 > [avision]   [26] 00100000b  40o  32d 20x
 > [avision]   [27] 00100000b  40o  32d 20x
 > [avision]   [28] 00100000b  40o  32d 20x
 > [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] 00110000b  60o  48d 30x
 > [avision]   [35] 00110001b  61o  49d 31x
 > [avision]   [36] 10100001b 241o 161d a1x
 > [avision]   [37] 00110000b  60o  48d 30x
 > [avision]   [38] 00110000b  60o  48d 30x
 > [avision]   [39] 01100111b 147o 103d 67x
 > [avision]   [40] 00010010b  22o  18d 12x
 > [avision]   [41] 11000000b 300o 192d c0x
 > [avision]   [42] 00010010b  22o  18d 12x
 > [avision]   [43] 11000000b 300o 192d c0x
 > [avision]   [44] 00010010b  22o  18d 12x
 > [avision]   [45] 11000000b 300o 192d c0x
 > [avision]   [46] 00010010b  22o  18d 12x
 > [avision]   [47] 11000000b 300o 192d c0x
 > [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] 00000010b   2o   2d  2x
 > [avision]   [78] 00001000b  10o   8d  8x
 > [avision]   [79] 00000111b   7o   7d  7x
 > [avision]   [80] 00111110b  76o  62d 3ex
 > [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] 00010010b  22o  18d 12x
 > [avision]   [90] 11000000b 300o 192d c0x
 > [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]   [96] 00000000b   0o   0d  0x
 > [avision]   [97] 00000000b   0o   0d  0x
 > [avision]   [98] 00000000b   0o   0d  0x
 > [avision]   [99] 00000000b   0o   0d  0x
 > [avision]   [100] 00000000b   0o   0d  0x
 > [avision]   [101] 00000000b   0o   0d  0x
 > [avision]   [102] 00000000b   0o   0d  0x
 > [avision]   [103] 00000000b   0o   0d  0x
 > [avision]   [104] 00000000b   0o   0d  0x
 > [avision]   [105] 00000000b   0o   0d  0x
 > [avision]   [106] 00000000b   0o   0d  0x
 > [avision]   [107] 00000000b   0o   0d  0x
 > [avision]   [108] 00000000b   0o   0d  0x
 > [avision]   [109] 00000000b   0o   0d  0x
 > [avision]   [110] 00000000b   0o   0d  0x
 > [avision]   [111] 00000000b   0o   0d  0x
 > [avision]   [112] 00000000b   0o   0d  0x
 > [avision]   [113] 00000000b   0o   0d  0x
 > [avision]   [114] 00000000b   0o   0d  0x
 > [avision]   [115] 00000000b   0o   0d  0x
 > [avision]   [116] 00000000b   0o   0d  0x
 > [avision]   [117] 00000000b   0o   0d  0x
 > [avision]   [118] 00000000b   0o   0d  0x
 > [avision]   [119] 00000000b   0o   0d  0x
 > [avision]   [120] 00000000b   0o   0d  0x
 > [avision]   [121] 00000000b   0o   0d  0x
 > [avision]   [122] 00000000b   0o   0d  0x
 > [avision]   [123] 00000000b   0o   0d  0x
 > [avision]   [124] 00000000b   0o   0d  0x
 > [avision]   [125] 00000000b   0o   0d  0x
 > [avision]   [126] 00000000b   0o   0d  0x
 > [avision]   [127] 00000000b   0o   0d  0x
 > [avision]   [128] 00000000b   0o   0d  0x
 > [avision]   [129] 00000000b   0o   0d  0x
 > [avision]   [130] 00000000b   0o   0d  0x
 > [avision]   [131] 00000000b   0o   0d  0x
 > [avision]   [132] 00000000b   0o   0d  0x
 > [avision]   [133] 00000000b   0o   0d  0x
 > [avision]   [134] 00000000b   0o   0d  0x
 > [avision]   [135] 00000000b   0o   0d  0x
 > [avision] attach: [8-15]  Vendor id.:      'HP      '
 > [avision] attach: [16-31] Product id.:     'C9930A          '
 > [avision] attach: [32-35] Product rev.:    '0.01'
 > [avision] attach: [36]    Bitfield: ADF 3-pass color BGR color plane
 > [avision] attach: [37]    Optical res.:    4800 dpi
 > [avision] attach: [38]    Maximum res.:    4800 dpi
 > [avision] attach: [39]    Bitfield1: Q_SCAN EXTENDED_RES
 > NEW_PROTOCOL AVISION
 > [avision] attach: [40-41] X res. in gray:  4800 dpi
 > [avision] attach: [42-43] Y res. in gray:  4800 dpi
 > [avision] attach: [44-45] X res. in color: 4800 dpi
 > [avision] attach: [46-47] Y res. in color: 4800 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
 > [avision] attach: [61]    bits per channel: 1 4 8 16
 > [avision] attach: [62]    scanner type: Flatbed (ADF)
 > [avision] attach: [75-76] Max shading target : f00
 > [avision] attach: [77-78] Max X of transparency: 520 dots * base_dpi
 > [avision] attach: [79-80] Max Y of transparency: 1854 dots * base_dpi
 > [avision] attach: [81-82] Max X of flatbed:      2552 dots * base_dpi
 > [avision] attach: [83-84] Max Y of flatbed:      4200 dots * base_dpi
 > [avision] attach: [85-86] Max X of ADF:          2552 dots * base_dpi
 > [avision] attach: [87-88] Max Y of ADF:          4200 dots * base_dpi
 > [avision] attach: [89-90] Res. in Ex. mode:      4800 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: [95]    ESA6:
 > [avision] attach: [128]    ESA7:
 > [avision] attach: [129]    YCbCr:
 > [avision] attach: optical resolution set to: 4800 dpi
 > [avision] attach: max resolution set to: 4800 dpi
 > [avision] attach: max channels per pixel: 3, max bits per channel: 16
 > [avision] attach: x/y-range for mode 0 is valid!
 > [avision] attach: Mode 0 range is now: 216.169333 x 355.600000 mm.
 > [avision] attach: x/y-range for mode 1 is valid!
 > [avision] attach: Mode 1 range is now: 44.126667 x 156.972000 mm.
 > [avision] attach: x/y-range for mode 2 is valid!
 > [avision] attach: Mode 2 range is now: 216.169333 x 355.600000 mm.
 > [avision] sane_reload_devices: Trying to find USB device 03f0 3905 ...
 > [avision] sane_reload_devices: Trying to find USB device 03f0 3805 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 026a ...
 > [avision] sane_reload_devices: Trying to find USB device 0686 4004 ...
 > [avision] sane_reload_devices: Trying to find USB device 0686 400d ...
 > [avision] sane_reload_devices: Trying to find USB device 0686 400e ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a15 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0a16 ...
 > [avision] sane_reload_devices: Trying to find USB device 04c5 1029 ...
 > [avision] sane_reload_devices: Trying to find USB device 04c5 10ef ...
 > [avision] sane_reload_devices: Trying to find USB device 040a 6001 ...
 > [avision] sane_reload_devices: Trying to find USB device 040a 6002 ...
 > [avision] sane_reload_devices: Trying to find USB device 040a 6003 ...
 > [avision] sane_reload_devices: Trying to find USB device 040a 6004 ...
 > [avision] sane_reload_devices: Trying to find USB device 040a 6005 ...
 > [avision] sane_reload_devices: Trying to find USB device 0638 0268 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0424 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0491 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0479 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 048f ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0420 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0421 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 047a ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0422 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0390 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 047b ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0423 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0493 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0497 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0498 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0499 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 049c ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0477 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 049d ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0448 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0490 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0449 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 048c ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 044c ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 048d ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 04a7 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0475 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 048e ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0446 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0495 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 047c ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0447 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0492 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 0478 ...
 > [avision] sane_reload_devices: Trying to find USB device 04a7 049a ...
 > [avision] sane_reload_devices: Trying to find USB device 0482 0335 ...
 > [avision] sane_open:
 > [avision] attach:
 > [avision] sane_open: using open_extended
 > [avision] sane_open: got 1048576 scsi_max_request_size
 > [avision] inquiry: length: 96
 > [avision] inquiry: inquiring ...
 > [avision] filling command to have a length of 10, was: 6
 > [avision] Timeouts: write: 30000, read: 1000, status: 1000
 > [avision] avision_usb_status: timeout 1000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] wait_ready: sending TEST_UNIT_READY
 > [avision] filling command to have a length of 10, was: 6
 > [avision] Timeouts: write: 30000, read: 15000, status: 15000
 > [avision] avision_usb_status: timeout 15000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] get_accessories_info
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) 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] get_accessories_info: Found ADF model number but the
 > ADF-present flag is not set. Trying to recover...
 > [avision] adf_reset
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: write 1 complete.
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: raw data:
 > [avision]   [0] 00001011b  13o  11d  bx
 > [avision]   [1] 00000110b   6o   6d  6x
 > [avision]   [2] 00000111b   7o   7d  7x
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: write 2 complete.
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: raw data:
 > [avision]   [0] 00000000b   0o   0d  0x
 > [avision]   [1] 00000000b   0o   0d  0x
 > [avision]   [2] 00000000b   0o   0d  0x
 > [avision]   [3] 01011101b 135o  93d 5dx
 > [avision] get_accessories_info: Waiting while ADF firmware resets...
 > [avision] wait_ready: sending TEST_UNIT_READY
 > [avision] filling command to have a length of 10, was: 6
 > [avision] Timeouts: write: 30000, read: 15000, status: 15000
 > [avision] avision_usb_status: timeout 15000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) 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] get_accessories_info: Found ADF model number but the
 > ADF-present flag is not set. Trying to recover...
 > [avision] adf_reset
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: write 1 complete.
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: raw data:
 > [avision]   [0] 00001011b  13o  11d  bx
 > [avision]   [1] 00000110b   6o   6d  6x
 > [avision]   [2] 00000111b   7o   7d  7x
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: write 2 complete.
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: raw data:
 > [avision]   [0] 00000000b   0o   0d  0x
 > [avision]   [1] 00000000b   0o   0d  0x
 > [avision]   [2] 00000000b   0o   0d  0x
 > [avision]   [3] 01011101b 135o  93d 5dx
 > [avision] get_accessories_info: Waiting while ADF firmware resets...
 > [avision] wait_ready: sending TEST_UNIT_READY
 > [avision] filling command to have a length of 10, was: 6
 > [avision] Timeouts: write: 30000, read: 15000, status: 15000
 > [avision] avision_usb_status: timeout 15000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) 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] get_accessories_info: Found ADF model number but the
 > ADF-present flag is not set. Trying to recover...
 > [avision] adf_reset
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: write 1 complete.
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: raw data:
 > [avision]   [0] 00001011b  13o  11d  bx
 > [avision]   [1] 00000110b   6o   6d  6x
 > [avision]   [2] 00000111b   7o   7d  7x
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: write 2 complete.
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: raw data:
 > [avision]   [0] 00000000b   0o   0d  0x
 > [avision]   [1] 00000000b   0o   0d  0x
 > [avision]   [2] 00000000b   0o   0d  0x
 > [avision]   [3] 01011101b 135o  93d 5dx
 > [avision] get_accessories_info: Waiting while ADF firmware resets...
 > [avision] wait_ready: sending TEST_UNIT_READY
 > [avision] filling command to have a length of 10, was: 6
 > [avision] Timeouts: write: 30000, read: 15000, status: 15000
 > [avision] avision_usb_status: timeout 15000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) 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] get_accessories_info: Found ADF model number but the
 > ADF-present flag is not set. Trying to recover...
 > [avision] adf_reset
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: write 1 complete.
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: raw data:
 > [avision]   [0] 00001011b  13o  11d  bx
 > [avision]   [1] 00000110b   6o   6d  6x
 > [avision]   [2] 00000111b   7o   7d  7x
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: write 2 complete.
 > [avision] Timeouts: write: 30000, read: 30000, status: 10000
 > [avision] avision_usb_status: timeout 10000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] adf_reset: raw data:
 > [avision]   [0] 00000000b   0o   0d  0x
 > [avision]   [1] 00000000b   0o   0d  0x
 > [avision]   [2] 00000000b   0o   0d  0x
 > [avision]   [3] 01011101b 135o  93d 5dx
 > [avision] get_accessories_info: Waiting while ADF firmware resets...
 > [avision] wait_ready: sending TEST_UNIT_READY
 > [avision] filling command to have a length of 10, was: 6
 > [avision] Timeouts: write: 30000, read: 15000, status: 15000
 > [avision] avision_usb_status: timeout 15000, 1 retries
 > [avision] ==> (bulk read) going down ...
 > [avision] <== (bulk read) got: 1, status: 0
 > [avision] get_accessories_info: Maximum retries attempted, ADF 
unresponsive.
 > [avision] sane_open: additional probe failed: Operation not supported
 > scanimage: open of device avision:libusb:001:007 failed: Operation
 > not supported
 > [avision] sane_exit: