Can't scan with Reflecta CrystalScan 7200
Hi,
I'm trying to use a Reflecta CrystalScan 7200 but I get the same error, no matter which frontend I use. This is the first scanner I've ever owned, so I know very little about this and can't make much sense of it.
I managed to get a lengthy debug log though, posted below.
Any help will be appreciated!
pieusb log
$ SANE_DEBUG_PIEUSB=15 scanimage -T -v
Output format is not set, using pnm as a default.
[19:04:56.408876] [sanei_debug] Setting debug level of pieusb to 15.
[19:04:56.408919] [pieusb] sane_init() build 1
[19:04:56.409109] [pieusb] sane_init() config file parsing usb 0x05e3 0x0145 0x47 0x00
[19:04:56.409126] [pieusb] sane_init() config file lists device 05e3 0145 47 00
[19:04:56.409144] [pieusb] sane_init() adding device 05e3 0145 47 00
[19:04:56.409160] [pieusb] sanei_pieusb_supported_device_list_add(): current 003: 05e3 0145 30 00
[19:04:56.409177] [pieusb] sanei_pieusb_supported_device_list_add(): current 003: 05e3 0145 36 00
[19:04:56.409193] [pieusb] sanei_pieusb_supported_device_list_add(): current 003: 05e3 0142 3a 01
[19:04:56.409208] [pieusb] sanei_pieusb_supported_device_list_add(): current 003: 0000 0000 00 00
[19:04:56.409224] [pieusb] sanei_pieusb_supported_device_list_add() add: 003: 05e3 0145 30 00
[19:04:56.409239] [pieusb] sanei_pieusb_supported_device_list_add() add: 003: 05e3 0145 36 00
[19:04:56.409255] [pieusb] sanei_pieusb_supported_device_list_add() add: 003: 05e3 0142 3a 01
[19:04:56.409272] [pieusb] sanei_pieusb_supported_device_list_add() add: 003: 05e3 0145 47 00
[19:04:56.409287] [pieusb] sanei_pieusb_supported_device_list_add() add: 003: 0000 0000 00 00
[19:04:56.409304] [pieusb] sane_init() config file parsing usb 0x05e3 0x0145 0x2e 0x00
[19:04:56.409320] [pieusb] sane_init() config file lists device 05e3 0145 2e 00
[19:04:56.409336] [pieusb] sane_init() adding device 05e3 0145 2e 00
[19:04:56.409351] [pieusb] sanei_pieusb_supported_device_list_add(): current 004: 05e3 0145 30 00
[19:04:56.409367] [pieusb] sanei_pieusb_supported_device_list_add(): current 004: 05e3 0145 36 00
[19:04:56.409383] [pieusb] sanei_pieusb_supported_device_list_add(): current 004: 05e3 0142 3a 01
[19:04:56.409398] [pieusb] sanei_pieusb_supported_device_list_add(): current 004: 05e3 0145 47 00
[19:04:56.409413] [pieusb] sanei_pieusb_supported_device_list_add(): current 004: 0000 0000 00 00
[19:04:56.409429] [pieusb] sanei_pieusb_supported_device_list_add() add: 004: 05e3 0145 30 00
[19:04:56.409445] [pieusb] sanei_pieusb_supported_device_list_add() add: 004: 05e3 0145 36 00
[19:04:56.409463] [pieusb] sanei_pieusb_supported_device_list_add() add: 004: 05e3 0142 3a 01
[19:04:56.409479] [pieusb] sanei_pieusb_supported_device_list_add() add: 004: 05e3 0145 47 00
[19:04:56.409493] [pieusb] sanei_pieusb_supported_device_list_add() add: 004: 05e3 0145 2e 00
[19:04:56.409510] [pieusb] sanei_pieusb_supported_device_list_add() add: 004: 0000 0000 00 00
[19:04:56.409529] [pieusb] sane_init() config file parsing usb 0x05e3 0x0142 0x2f 0x00
[19:04:56.409545] [pieusb] sane_init() config file lists device 05e3 0142 2f 00
[19:04:56.409560] [pieusb] sane_init() adding device 05e3 0142 2f 00
[19:04:56.409576] [pieusb] sanei_pieusb_supported_device_list_add(): current 005: 05e3 0145 30 00
[19:04:56.409593] [pieusb] sanei_pieusb_supported_device_list_add(): current 005: 05e3 0145 36 00
[19:04:56.409611] [pieusb] sanei_pieusb_supported_device_list_add(): current 005: 05e3 0142 3a 01
[19:04:56.409629] [pieusb] sanei_pieusb_supported_device_list_add(): current 005: 05e3 0145 47 00
[19:04:56.409647] [pieusb] sanei_pieusb_supported_device_list_add(): current 005: 05e3 0145 2e 00
[19:04:56.409664] [pieusb] sanei_pieusb_supported_device_list_add(): current 005: 0000 0000 00 00
[19:04:56.409682] [pieusb] sanei_pieusb_supported_device_list_add() add: 005: 05e3 0145 30 00
[19:04:56.409698] [pieusb] sanei_pieusb_supported_device_list_add() add: 005: 05e3 0145 36 00
[19:04:56.409716] [pieusb] sanei_pieusb_supported_device_list_add() add: 005: 05e3 0142 3a 01
[19:04:56.409731] [pieusb] sanei_pieusb_supported_device_list_add() add: 005: 05e3 0145 47 00
[19:04:56.409747] [pieusb] sanei_pieusb_supported_device_list_add() add: 005: 05e3 0145 2e 00
[19:04:56.409758] [pieusb] sanei_pieusb_supported_device_list_add() add: 005: 05e3 0142 2f 00
[19:04:56.409768] [pieusb] sanei_pieusb_supported_device_list_add() add: 005: 0000 0000 00 00
[19:04:56.409788] [pieusb] sane_init() looking for scanner 05e3 0145 model 30, flags 00
[19:04:56.409802] [pieusb] sanei_pieusb_find_device_callback: libusb:001:003
[19:04:56.409868] [pieusb] sanei_pieusb_cmd_inquiry()
[19:04:56.409885] [pieusb] *** sanei_pieusb_command(12:Inquiry): size 0x05
[19:04:56.409900] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:04:56.409920] [pieusb] _pieusb_scsi_command(): 12:Inquiry
Cmd 00000000: 12 00 00 00 05 00 ......
[19:04:56.421813] [pieusb] _pieusb_scsi_command usbstat 0x01
[19:04:56.421845] [pieusb] _pieusb_scsi_command data in
In 00000000: 06 00 02 01 b4 ....4
[19:04:56.432524] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:04:56.432555] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:04:56.432954] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:04:56.432987] [pieusb] sanei_pieusb_command() finished with state 0
[19:04:56.433003] [pieusb] sanei_pieusb_cmd_inquiry()
[19:04:56.433016] [pieusb] *** sanei_pieusb_command(12:Inquiry): size 0xb8
[19:04:56.433032] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:04:56.433045] [pieusb] _pieusb_scsi_command(): 12:Inquiry
Cmd 00000000: 12 00 00 00 b8 00 ....8.
[19:04:56.445154] [pieusb] _pieusb_scsi_command usbstat 0x01
[19:04:56.445180] [pieusb] _pieusb_scsi_command data in
In 00000000: 06 00 02 01 b4 00 00 00 50 49 45 20 20 20 20 20 ....4...PIE
00000010: 53 46 20 53 63 61 6e 6e 65 72 00 00 00 00 00 00 SF Scanner......
00000020: 31 2e 37 30 20 1c 20 1c b8 29 e8 1a 9e 35 07 00 1.70 . .8)h..5..
00000030: 09 4b 61 02 0c 00 2c 01 00 00 00 00 00 00 00 00 .Ka...,.........
00000040: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
00000050: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
00000060: 31 2e 30 32 08 64 64 01 c4 09 64 00 d0 02 86 04 1.02.dd.D.d.P...
00000070: bc 10 c0 15 30 00 00 00 50 49 45 00 32 30 30 34 <.@.0...PIE.2004
000000b8 bytes clipped
[19:04:56.467796] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:04:56.467818] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:04:56.468205] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:04:56.468238] [pieusb] sanei_pieusb_command() finished with state 0
[19:04:56.468294] [pieusb] INQUIRY:
[19:04:56.468309] [pieusb] ========
[19:04:56.468319] [pieusb]
[19:04:56.468329] [pieusb] vendor........................: 'PIE'
[19:04:56.468340] [pieusb] product.......................: 'SF Scanner'
[19:04:56.468350] [pieusb] model .......................: 0x0030
[19:04:56.468366] [pieusb] version.......................: '1.70'
[19:04:56.468372] [pieusb] X resolution..................: 7200 dpi
[19:04:56.468388] [pieusb] Y resolution..................: 7200 dpi
[19:04:56.468394] [pieusb] pixel resolution..............: 7200 dpi
[19:04:56.468400] [pieusb] fb width......................: 1.483333 in
[19:04:56.468409] [pieusb] fb length.....................: 0.956667 in
[19:04:56.468417] [pieusb] transparency width............: 0.495000 in
[19:04:56.468424] [pieusb] transparency length...........: 0.612500 in
[19:04:56.468431] [pieusb] transparency offset...........: 720,1158
[19:04:56.468438] [pieusb] # of halftones................: 8
[19:04:56.468444] [pieusb] One pass color................: yes
[19:04:56.468451] [pieusb] Filters.......................: Infrared Red Green Blue (9e)
[19:04:56.468458] [pieusb] Color depths..................: 16 bit 12 bit 8 bit 1 bit (35)
[19:04:56.468465] [pieusb] Color Format..................: Indexed Line Pixel (07)
[19:04:56.468472] [pieusb] Image Format..................: OKLine Intel (09)
[19:04:56.468478] [pieusb] Scan Capability...............: ExtCal DisCal 3 speeds (4b)
[19:04:56.468485] [pieusb] Optional Devices..............: ADF (61)
[19:04:56.468492] [pieusb] Enhancement...................: 02
[19:04:56.468499] [pieusb] Gamma bits....................: 12
[19:04:56.468505] [pieusb] Fast Preview Resolution.......: 300
[19:04:56.468512] [pieusb] Min Highlight.................: 100
[19:04:56.468518] [pieusb] Max Shadow....................: 100
[19:04:56.468525] [pieusb] Cal Eqn.......................: 1
[19:04:56.468531] [pieusb] Min Exposure..................: 100
[19:04:56.468537] [pieusb] Max Exposure..................: 10000
[19:04:56.468543] [pieusb] x0,y0 x1,y1...................: 720,1158 4284,5568
[19:04:56.468550] [pieusb] production....................: 'PIE'
[19:04:56.468556] [pieusb] timestamp.....................: '2004/11/19 13:00PM'
[19:04:56.468563] [pieusb] signature.....................: 'Flash ROM 29EE512 BY:Chen Tsung-Yu '
[19:04:56.468570] [pieusb] sanei_pieusb_find_device_callback: success
[19:04:56.468576] [pieusb] sane_init() looking for scanner 05e3 0145 model 36, flags 00
[19:04:56.468592] [pieusb] sanei_pieusb_find_device_callback: libusb:001:003
[19:04:56.468602] [pieusb] sane_init() looking for scanner 05e3 0142 model 3a, flags 01
[19:04:56.468609] [pieusb] sane_init() looking for scanner 05e3 0145 model 47, flags 00
[19:04:56.468615] [pieusb] sanei_pieusb_find_device_callback: libusb:001:003
[19:04:56.468622] [pieusb] sane_init() looking for scanner 05e3 0145 model 2e, flags 00
[19:04:56.468628] [pieusb] sanei_pieusb_find_device_callback: libusb:001:003
[19:04:56.468635] [pieusb] sane_init() looking for scanner 05e3 0142 model 2f, flags 00
[19:04:56.468643] [pieusb] sane_get_devices
[19:05:02.274245] [pieusb] sane_open(libusb:001:003)
[19:05:02.274315] [pieusb] sanei_pieusb_init_options
[19:05:02.274326] [pieusb] sanei_pieusb_wait_ready()
[19:05:02.274331] [pieusb] sanei_pieusb_cmd_test_unit_ready()
[19:05:02.274336] [pieusb] *** sanei_pieusb_command(00:Test Unit Ready): size 0x00
[19:05:02.274341] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.274346] [pieusb] _pieusb_scsi_command(): 00:Test Unit Ready
Cmd 00000000: 00 00 00 00 00 00 ......
[19:05:02.286079] [pieusb] _pieusb_scsi_command usbstat 0x03
[19:05:02.286124] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.286142] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.286455] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.286475] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.286492] [pieusb] sanei_pieusb_cmd_test_unit_ready() return status = Success
[19:05:02.286508] [pieusb] -> sanei_pieusb_cmd_test_unit_ready: 0
[19:05:02.286527] [pieusb] sane_get_option_descriptor() option=0
[19:05:02.286544] [pieusb] sane_control_option()
[19:05:02.286559] [pieusb] get [#0]
[19:05:02.286575] [pieusb] get [#0] val=44
[19:05:02.286592] [pieusb] sane_get_option_descriptor() option=0
[19:05:02.286608] [pieusb] sane_control_option()
[19:05:02.286624] [pieusb] get [#0]
[19:05:02.286639] [pieusb] get [#0] val=44
[19:05:02.286655] [pieusb] sane_get_option_descriptor() option=1
[19:05:02.286670] [pieusb] sane_get_option_descriptor() option=2
[19:05:02.286687] [pieusb] sane_get_option_descriptor() option=3
[19:05:02.286704] [pieusb] sane_get_option_descriptor() option=4
[19:05:02.286721] [pieusb] sane_get_option_descriptor() option=5
[19:05:02.286741] [pieusb] sane_get_option_descriptor() option=6
[19:05:02.286753] [pieusb] sane_get_option_descriptor() option=7
[19:05:02.286764] [pieusb] sane_get_option_descriptor() option=8
[19:05:02.286775] [pieusb] sane_get_option_descriptor() option=9
[19:05:02.286791] [pieusb] sane_get_option_descriptor() option=10
[19:05:02.286807] [pieusb] sane_get_option_descriptor() option=11
[19:05:02.286824] [pieusb] sane_get_option_descriptor() option=12
[19:05:02.286844] [pieusb] sane_get_option_descriptor() option=13
[19:05:02.286854] [pieusb] sane_get_option_descriptor() option=14
[19:05:02.286864] [pieusb] sane_get_option_descriptor() option=15
[19:05:02.286875] [pieusb] sane_get_option_descriptor() option=16
[19:05:02.286886] [pieusb] sane_get_option_descriptor() option=17
[19:05:02.286896] [pieusb] sane_get_option_descriptor() option=18
[19:05:02.286906] [pieusb] sane_get_option_descriptor() option=19
[19:05:02.286915] [pieusb] sane_get_option_descriptor() option=20
[19:05:02.286925] [pieusb] sane_get_option_descriptor() option=21
[19:05:02.286933] [pieusb] sane_get_option_descriptor() option=22
[19:05:02.286943] [pieusb] sane_get_option_descriptor() option=23
[19:05:02.286952] [pieusb] sane_get_option_descriptor() option=24
[19:05:02.286961] [pieusb] sane_get_option_descriptor() option=25
[19:05:02.286969] [pieusb] sane_get_option_descriptor() option=26
[19:05:02.286977] [pieusb] sane_get_option_descriptor() option=27
[19:05:02.286985] [pieusb] sane_get_option_descriptor() option=28
[19:05:02.286992] [pieusb] sane_get_option_descriptor() option=29
[19:05:02.287001] [pieusb] sane_get_option_descriptor() option=30
[19:05:02.287010] [pieusb] sane_get_option_descriptor() option=31
[19:05:02.287020] [pieusb] sane_get_option_descriptor() option=32
[19:05:02.287030] [pieusb] sane_get_option_descriptor() option=33
[19:05:02.287040] [pieusb] sane_get_option_descriptor() option=34
[19:05:02.287050] [pieusb] sane_get_option_descriptor() option=35
[19:05:02.287060] [pieusb] sane_get_option_descriptor() option=36
[19:05:02.287069] [pieusb] sane_get_option_descriptor() option=37
[19:05:02.287086] [pieusb] sane_get_option_descriptor() option=38
[19:05:02.287110] [pieusb] sane_get_option_descriptor() option=39
[19:05:02.287120] [pieusb] sane_get_option_descriptor() option=40
[19:05:02.287131] [pieusb] sane_get_option_descriptor() option=41
[19:05:02.287140] [pieusb] sane_get_option_descriptor() option=42
[19:05:02.287150] [pieusb] sane_get_option_descriptor() option=43
[19:05:02.287161] [pieusb] sane_control_option()
[19:05:02.287170] [pieusb] get br-x [#15]
[19:05:02.287180] [pieusb] get br-x [#15] val=2469178
[19:05:02.287189] [pieusb] sane_control_option()
[19:05:02.287198] [pieusb] get tl-x [#13]
[19:05:02.287206] [pieusb] get tl-x [#13] val=0
[19:05:02.287214] [pieusb] sane_control_option()
[19:05:02.287221] [pieusb] get br-y [#16]
[19:05:02.287231] [pieusb] get br-y [#16] val=1592481
[19:05:02.287242] [pieusb] sane_control_option()
[19:05:02.287253] [pieusb] get tl-y [#14]
[19:05:02.287263] [pieusb] get tl-y [#14] val=0
[19:05:02.287276] [pieusb] sane_control_option()
[19:05:02.287286] [pieusb] get tl-x [#13]
[19:05:02.287296] [pieusb] get tl-x [#13] val=0
[19:05:02.287304] [pieusb] sane_get_option_descriptor() option=15
[19:05:02.287313] [pieusb] sane_control_option()
[19:05:02.287321] [pieusb] set br-x [#15] to 37.676666
[19:05:02.287336] [pieusb] sane_control_option()
[19:05:02.287344] [pieusb] get tl-y [#14]
[19:05:02.287352] [pieusb] get tl-y [#14] val=0
[19:05:02.287360] [pieusb] sane_get_option_descriptor() option=16
[19:05:02.287368] [pieusb] sane_control_option()
[19:05:02.287377] [pieusb] set br-y [#16] to 24.299332
[19:05:02.287394] [pieusb] sane_start()
[19:05:02.287405] [pieusb] sanei_pieusb_cmd_read_state()
[19:05:02.287417] [pieusb] *** sanei_pieusb_command(dd:Read State): size 0x0c
[19:05:02.287429] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.287440] [pieusb] _pieusb_scsi_command(): dd:Read State
Cmd 00000000: dd 00 00 00 0c 00 ].....
[19:05:02.298989] [pieusb] _pieusb_scsi_command usbstat 0x01
[19:05:02.299022] [pieusb] _pieusb_scsi_command data in
In 00000000: 00 00 ff ff ff 00 01 7f 01 49 45 20 .........IE
[19:05:02.301072] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.301098] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.301476] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.301503] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.301524] [pieusb] sanei_pieusb_cmd_read_state(): button 0, warmingUp 0, scanning 1, busy? 1
[19:05:02.301546] [pieusb] sanei_pieusb_cmd_set_exposure_time()
[19:05:02.301565] [pieusb] *** sanei_pieusb_command(0a:Write): size 0x08
[19:05:02.301585] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.301603] [pieusb] _pieusb_scsi_command(): 0a:Write
Cmd 00000000: 0a 00 00 00 08 00 ......
[19:05:02.313227] [pieusb] _pieusb_scsi_command usbstat 0x00
Out 00000000: 13 00 04 00 02 00 64 00 ......d.
[19:05:02.317351] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.317384] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.317762] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.317803] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.317827] [pieusb] *** sanei_pieusb_command(0a:Write): size 0x08
[19:05:02.317840] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.317854] [pieusb] _pieusb_scsi_command(): 0a:Write
Cmd 00000000: 0a 00 00 00 08 00 ......
[19:05:02.329203] [pieusb] _pieusb_scsi_command usbstat 0x00
Out 00000000: 13 00 04 00 04 00 64 00 ......d.
[19:05:02.333603] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.333640] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.333977] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.334003] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.334028] [pieusb] *** sanei_pieusb_command(0a:Write): size 0x08
[19:05:02.334056] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.334076] [pieusb] _pieusb_scsi_command(): 0a:Write
Cmd 00000000: 0a 00 00 00 08 00 ......
[19:05:02.345227] [pieusb] _pieusb_scsi_command usbstat 0x00
Out 00000000: 13 00 04 00 08 00 64 00 ......d.
[19:05:02.349077] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.349104] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.349473] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.349497] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.349520] [pieusb] sanei_pieusb_cmd_set_highlight_shadow()
[19:05:02.349536] [pieusb] *** sanei_pieusb_command(0a:Write): size 0x08
[19:05:02.349550] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.349562] [pieusb] _pieusb_scsi_command(): 0a:Write
Cmd 00000000: 0a 00 00 00 08 00 ......
[19:05:02.361097] [pieusb] _pieusb_scsi_command usbstat 0x00
Out 00000000: 14 00 04 00 02 00 64 00 ......d.
[19:05:02.366092] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.366123] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.366470] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.366495] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.366511] [pieusb] *** sanei_pieusb_command(0a:Write): size 0x08
[19:05:02.366530] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.366554] [pieusb] _pieusb_scsi_command(): 0a:Write
Cmd 00000000: 0a 00 00 00 08 00 ......
[19:05:02.378097] [pieusb] _pieusb_scsi_command usbstat 0x00
Out 00000000: 14 00 04 00 04 00 64 00 ......d.
[19:05:02.382233] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.382261] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.382612] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.382637] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.382664] [pieusb] *** sanei_pieusb_command(0a:Write): size 0x08
[19:05:02.382692] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.382725] [pieusb] _pieusb_scsi_command(): 0a:Write
Cmd 00000000: 0a 00 00 00 08 00 ......
[19:05:02.394086] [pieusb] _pieusb_scsi_command usbstat 0x00
Out 00000000: 14 00 04 00 08 00 64 00 ......d.
[19:05:02.398857] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.398887] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.399236] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.399261] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.399277] [pieusb] sanei_pieusb_cmd_get_shading_parms()
[19:05:02.399301] [pieusb] *** sanei_pieusb_command(0a:Write): size 0x06
[19:05:02.399328] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.399350] [pieusb] _pieusb_scsi_command(): 0a:Write
Cmd 00000000: 0a 00 00 00 06 00 ......
[19:05:02.411221] [pieusb] _pieusb_scsi_command usbstat 0x00
Out 00000000: 95 00 00 00 00 00 ......
[19:05:02.414358] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.414386] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.414736] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.414760] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.414776] [pieusb] *** sanei_pieusb_command(08:Read): size 0x20
[19:05:02.414795] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.414819] [pieusb] _pieusb_scsi_command(): 08:Read
Cmd 00000000: 08 00 00 00 20 00 .... .
[19:05:02.426344] [pieusb] _pieusb_scsi_command usbstat 0x01
[19:05:02.426373] [pieusb] _pieusb_scsi_command data in
In 00000000: 95 00 1c 00 04 06 04 00 00 10 10 2d dc 14 08 10 ...........-\...
00000010: 10 2d dc 14 10 10 10 2d dc 14 20 10 10 2d dc 14 .-\....-\. ..-\.
[19:05:02.430330] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.430344] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.431345] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.431356] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.431363] [pieusb] shading_width 5340
[19:05:02.431389] [pieusb] Num options = 44
[19:05:02.431407] [pieusb] Option 1: Scan Mode = (null)
[19:05:02.431420] [pieusb] Option 2: mode = Color
[19:05:02.431432] [pieusb] Option 3: depth = 8
[19:05:02.431443] [pieusb] Option 4: resolution = 300.000000
[19:05:02.431480] [pieusb] Option 5: halftone-pattern = 4x4 SQUARE
[19:05:02.431503] [pieusb] Option 6: threshold = 50.000000
[19:05:02.431530] [pieusb] Option 7: sharpen = 0
[19:05:02.431554] [pieusb] Option 8: shading-analysis = 0
[19:05:02.431578] [pieusb] Option 9: fast-infrared = 0
[19:05:02.431603] [pieusb] Option 10: advcane = 1
[19:05:02.431625] [pieusb] Option 11: calibration = from internal test
[19:05:02.431649] [pieusb] Option 12: Geometry = (null)
[19:05:02.431673] [pieusb] Option 13: tl-x = 0.000000
[19:05:02.431699] [pieusb] Option 14: tl-y = 0.000000
[19:05:02.431724] [pieusb] Option 15: br-x = 37.676666
[19:05:02.431752] [pieusb] Option 16: br-y = 24.299332
[19:05:02.431778] [pieusb] Option 17: Enhancement = (null)
[19:05:02.431802] [pieusb] Option 18: correct-shading = 1
[19:05:02.431825] [pieusb] Option 19: correct-infrared = 0
[19:05:02.431849] [pieusb] Option 20: clean-image = 0
[19:05:02.431873] [pieusb] Option 21: gain-adjust = * 0.8
[19:05:02.431898] [pieusb] Option 22: crop = Inside
[19:05:02.431921] [pieusb] Option 23: smooth = 0
[19:05:02.431944] [pieusb] Option 24: srgb = 0
[19:05:02.431969] [pieusb] Option 25: invert = 0
[19:05:02.431993] [pieusb] Option 26: Advanced = (null)
[19:05:02.432017] [pieusb] Option 27: preview = 0
[19:05:02.432041] [pieusb] Option 28: save-shading-data = 0
[19:05:02.432063] [pieusb] Option 29: save-ccdmask = 0
[19:05:02.432087] [pieusb] Option 30: light = 4
[19:05:02.432110] [pieusb] Option 31: double-times = 0
[19:05:02.432134] [pieusb] Option 32: exposure-time-r = 2937
[19:05:02.432159] [pieusb] Option 33: exposure-time-g = 2937
[19:05:02.432182] [pieusb] Option 34: exposure-time-b = 2937
[19:05:02.432206] [pieusb] Option 35: exposure-time-i = 2937
[19:05:02.432229] [pieusb] Option 36: gain-r = 19
[19:05:02.432252] [pieusb] Option 37: gain-g = 19
[19:05:02.432275] [pieusb] Option 38: gain-b = 19
[19:05:02.432299] [pieusb] Option 39: gain-i = 19
[19:05:02.432322] [pieusb] Option 40: offset-r = 0
[19:05:02.432346] [pieusb] Option 41: offset-g = 0
[19:05:02.432370] [pieusb] Option 42: offset-b = 0
[19:05:02.432393] [pieusb] Option 43: offset-i = 0
[19:05:02.432418] [pieusb] sanei_pieusb_cmd_set_scan_frame()
[19:05:02.432441] [pieusb] x0,y0 = 0,0
[19:05:02.432465] [pieusb] x1,y1 = 10679,6887
[19:05:02.432487] [pieusb] index = 128
[19:05:02.432511] [pieusb] *** sanei_pieusb_command(0a:Write): size 0x0e
[19:05:02.432536] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.432560] [pieusb] _pieusb_scsi_command(): 0a:Write
Cmd 00000000: 0a 00 00 00 0e 00 ......
[19:05:02.444220] [pieusb] _pieusb_scsi_command usbstat 0x00
Out 00000000: 12 00 0a 00 80 00 00 00 00 00 b7 29 e7 1a ..........7)g.
[19:05:02.452975] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.453015] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.453340] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.453361] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.453378] [pieusb] sanei_pieusb_set_frame_from_options(): sanei_pieusb_cmd_set_scan_frame status Success
[19:05:02.453395] [pieusb] sanei_pieusb_set_gain_offset(): mode = from internal test
[19:05:02.453407] [pieusb] sanei_pieusb_set_gain_offset(): get calibration data from scanner
[19:05:02.453416] [pieusb] sanei_pieusb_cmd_get_gain_offset()
[19:05:02.453424] [pieusb] *** sanei_pieusb_command(d7:Read Gain Offset): size 0x67
[19:05:02.453436] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.453449] [pieusb] _pieusb_scsi_command(): d7:Read Gain Offset
Cmd 00000000: d7 00 00 00 67 00 W...g.
[19:05:02.468269] [pieusb] _pieusb_scsi_command usbstat 0x01
[19:05:02.468313] [pieusb] _pieusb_scsi_command data in
In 00000000: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
00000010: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
00000020: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
00000030: 00 00 00 00 00 00 00 00 00 00 28 e6 00 00 00 00 ..........(f....
00000040: 00 00 00 00 00 00 00 00 00 00 00 00 79 0b 00 40 ............y..@
00000050: 00 00 02 00 00 40 00 00 02 01 00 01 eb ff fa ff .....@......k.z.
00000060: ff fd 79 0b 00 ff 0f .}y....
[19:05:02.476879] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.476895] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.477219] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.477238] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.477250] [pieusb] sanei_pieusb_cmd_get_gain_offset() set:
[19:05:02.477261] [pieusb] saturationlevels = 0-0-58920
[19:05:02.477271] [pieusb] ---
[19:05:02.477281] [pieusb] exposure times = 0-0-0-2937
[19:05:02.477298] [pieusb] gain = 0-0-0-15
[19:05:02.477304] [pieusb] offset = 0-0-0-0
[19:05:02.477318] [pieusb] light = 00
[19:05:02.477324] [pieusb] double times = 00
[19:05:02.477330] [pieusb] extra entries = 00
[19:05:02.477336] [pieusb] sanei_pieusb_cmd_set_gain_offset()
[19:05:02.477342] [pieusb] sanei_pieusb_cmd_set_gain_offset() set:
[19:05:02.477348] [pieusb] exposure times = 0-0-0-2937
[19:05:02.477354] [pieusb] gain = 0-0-0-15
[19:05:02.477360] [pieusb] offset = 0-0-0-0
[19:05:02.477366] [pieusb] light = 00
[19:05:02.477372] [pieusb] double times = 00
[19:05:02.477378] [pieusb] extra entries = 00
[19:05:02.477385] [pieusb] *** sanei_pieusb_command(dc:Write Gain Offset): size 0x1d
[19:05:02.477391] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.477398] [pieusb] _pieusb_scsi_command(): dc:Write Gain Offset
Cmd 00000000: dc 00 00 00 1d 00 \.....
[19:05:02.487972] [pieusb] _pieusb_scsi_command usbstat 0x03
[19:05:02.488001] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.488017] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.488346] [pieusb] sanei_pieusb_command loop, status 2:Check
[19:05:02.488369] [pieusb] sanei_pieusb_cmd_get_sense()
[19:05:02.488390] [pieusb] *** sanei_pieusb_command(03:Request Sense): size 0x0e
[19:05:02.488406] [pieusb] sanei_pieusb_command loop, status 8:Again
[19:05:02.488420] [pieusb] _pieusb_scsi_command(): 03:Request Sense
Cmd 00000000: 03 00 00 00 0e 00 ......
[19:05:02.499747] [pieusb] _pieusb_scsi_command usbstat 0x01
[19:05:02.499793] [pieusb] _pieusb_scsi_command data in
In 00000000: 70 00 05 00 00 00 00 06 00 00 00 00 1a 00 p.............
[19:05:02.503201] [pieusb] _pieusb_scsi_command returned 3:Busy
[19:05:02.503214] [pieusb] sanei_pieusb_command loop, status 3:Busy
[19:05:02.503507] [pieusb] sanei_pieusb_command loop, status 0:Ok
[19:05:02.503543] [pieusb] sanei_pieusb_command() finished with state 0
[19:05:02.503563] [pieusb] sense details:
[19:05:02.503568] [pieusb] error......... : 0x70
[19:05:02.503576] [pieusb] segment....... : 0
[19:05:02.503586] [pieusb] senseKey...... : 0x05
[19:05:02.503591] [pieusb] info.......... : 00 00 00 00
[19:05:02.503597] [pieusb] addLength..... : 6
[19:05:02.503601] [pieusb] cmdInfo....... : 00 00 00 00
[19:05:02.503606] [pieusb] senseCode..... : 0x1a
[19:05:02.503611] [pieusb] senseQualifier : 0x00
[19:05:02.503621] [pieusb] sense: Illegal Request: senseCode 0x1a, senseQualifier 0x00
[19:05:02.503631] [pieusb] sanei_pieusb_command() finished with state 4
[19:05:02.503642] [pieusb] sanei_pieusb_set_gain_offset(): status Invalid argument
scanimage: sane_start: Error during device I/O
[19:05:02.503657] [pieusb] sane_cancel
[19:05:02.503667] [pieusb] sane_close()
[19:05:02.702840] [pieusb] sane_exit()
Edited by Plamen Batalski