Alan Stern wrote at wed 06/16, 2010 at 17:21 -0400: > On Wed, 16 Jun 2010, Stanislav Brabec wrote: > > I bought an Adaptec USBXChange USB to SCSI converter, found a firmware > > uploader patch and started to test it. > > > > SCSI Bernoulli disc works perfectly with the adapter but scanning on > > UMAX Astra 1200S hangs early. > > > > Reading large kernel logs I suspect that scanner transfers incomplete > > SCSI frames. They are converted to incomplete USB frames and it seems > > that they cause switching the kernel or the device into an erroneous > > state and never reach sane-umax using /dev/sg0 interface. The device > > starts to respond ENODEV. > > > > Attaching SG_IO as they were captured with strace with USB to SCSI > > converter and with PCI SCSI card. > > The strace log doesn't contain enough information. It would help to > see a usbmon trace of the same events (see > Documentation/usb/usbmon.txt). Attaching an usbmon log of this action: Started monitor, requested preview in xsane, scanning hangs, wait few minutes, device releases without preview done. > > Tested on openSUSE's linux-2.6.31.12. > > > > Full kernel logs: > > http://www.penguin.cz/~utx/temp/usbscsi-scan-logs.tar.bz2 > > http://www.penguin.cz/~utx/temp/usbscsi-scan-logs2.tar.bz2 > > Those logs contain an awful lot of "Medium not present" errors, so many > that they totally obscure the useful information. You need to suppress > all those TEST UNIT READY commands during your test (they are probably > meant for other devices anyway). Yes, I enabled USB logging into syslog, and I needed to use mouse and keyboard for xsane. Most of the events came from mouse. usbmon shows only relevant data. This seems to be relevant to the frame that causes failure: For example in usbscsi-scan-logs.tar.bz2, file scan.messages, line 126953 Mar 31 11:32:39 utx kernel: [ 908.830767] usb-storage: Status code -121; transferred 0/18 Mar 31 11:32:39 utx kernel: [ 908.830769] usb-storage: -- short read transfer Mar 31 11:32:39 utx kernel: [ 908.830771] usb-storage: Bulk data transfer result 0x1 Mar 31 11:32:39 utx kernel: [ 908.830773] usb-storage: Attempting to get CSW... Mar 31 11:32:39 utx kernel: [ 908.830775] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes > Also, there's no information about what was happening when each of > those log files was created. The same as described above. Here is the usbmon log: ffff880110466180 2391495218 S Bo:7:004:2 -115 31 = 55534243 0a000000 05000000 80000612 00020005 00000000 00000000 000000 ffff880110466180 2391497066 C Bo:7:004:2 0 31 > ffff880037bee740 2391497077 S Bi:7:004:2 -115 5 < ffff880037bee740 2391501068 C Bi:7:004:2 0 5 = 06080202 9c ffff880110466180 2391501080 S Bi:7:004:2 -115 13 < ffff880110466180 2391502066 C Bi:7:004:2 0 13 = 55534253 0a000000 00000000 00 ffff880110466180 2391502118 S Bo:7:004:2 -115 31 = 55534243 0b000000 a1000000 80000612 000200a1 00000000 00000000 000000 ffff880110466180 2391503067 C Bo:7:004:2 0 31 > ffff880037bee740 2391503078 S Bi:7:004:2 -115 161 < ffff880037bee740 2391536069 C Bi:7:004:2 -121 160 = 06080202 9c000000 554d4158 20202020 41737472 61203132 30305320 20202020 ffff880110466180 2391536081 S Bi:7:004:2 -115 13 < ffff880110466180 2391537069 C Bi:7:004:2 0 13 = 55534253 0b000000 01000000 00 ffff880110466180 2399302244 S Bo:7:004:2 -115 31 = 55534243 0c000000 00000000 00000600 00000000 00000000 00000000 000000 ffff880110466180 2399304151 C Bo:7:004:2 0 31 > ffff880110466180 2399304163 S Bi:7:004:2 -115 13 < ffff880110466180 2399307149 C Bi:7:004:2 0 13 = 55534253 0c000000 00000000 00 ffff880110466180 2399307188 S Bo:7:004:2 -115 31 = 55534243 0d000000 00000000 00000616 00000000 00000000 00000000 000000 ffff880110466180 2399308147 C Bo:7:004:2 0 31 > ffff880110466180 2399308162 S Bi:7:004:2 -115 13 < ffff880110466180 2399312151 C Bi:7:004:2 0 13 = 55534253 0d000000 00000000 00 ffff880110466180 2399312202 S Bo:7:004:2 -115 31 = 55534243 0e000000 060c0000 00000a2a 00030000 00000c06 00000000 000000 ffff880110466180 2399313148 C Bo:7:004:2 0 31 > ffff8800a0895380 2399313164 S Bo:7:004:2 -115 3078 = 23000400 01000000 00000101 01010202 02020303 03030404 04040505 05050606 ffff8800a0895380 2399742154 C Bo:7:004:2 0 3078 > ffff880110466180 2399742166 S Bi:7:004:2 -115 13 < ffff880110466180 2399764153 C Bi:7:004:2 0 13 = 55534253 0e000000 00000000 00 ffff880110466180 2399764204 S Bo:7:004:2 -115 31 = 55534243 0f000000 fe000000 00000a24 00000000 000000fe 00000000 000000 ffff880110466180 2399765155 C Bo:7:004:2 0 31 > ffff8800c05bc9c0 2399765166 S Bo:7:004:2 -115 254 = 00000000 000000f6 01000041 00410000 00000000 00000000 27d70000 419f7f7f ffff8800c05bc9c0 2399768153 C Bo:7:004:2 0 254 > ffff880110466180 2399768168 S Bi:7:004:2 -115 13 < ffff880110466180 2399908155 C Bi:7:004:2 0 13 = 55534253 0f000000 00000000 00 ffff880110466180 2399908191 S Bo:7:004:2 -115 31 = 55534243 10000000 01000000 0000061b 00000001 80000000 00000000 000000 ffff880110466180 2399908777 C Bo:7:004:2 0 31 > ffff8800c05bc9c0 2399908783 S Bo:7:004:2 -115 1 = 00 ffff8800c05bc9c0 2399909154 C Bo:7:004:2 0 1 > ffff880110466180 2399909165 S Bi:7:004:2 -115 13 < ffff880110466180 2399912156 C Bi:7:004:2 0 13 = 55534253 10000000 00000000 00 ffff880110466180 2399912185 S Bo:7:004:2 -115 31 = 55534243 11000000 00000000 00000600 00000000 00000000 00000000 000000 ffff880110466180 2399913155 C Bo:7:004:2 0 31 > ffff880110466180 2399913170 S Bi:7:004:2 -115 13 < ffff880110466180 2400250157 C Bi:7:004:2 0 13 = 55534253 11000000 00000000 01 ffff880110466180 2400250169 S Bo:7:004:2 -115 31 = 55534243 12000000 12000000 80000603 00000012 00000000 00000000 000000 ffff880110466180 2400252160 C Bo:7:004:2 0 31 > ffff8800c05bc9c0 2400252177 S Bi:7:004:2 -115 18 < ffff8800c05bc9c0 2400588162 C Bi:7:004:2 -121 0 ffff880110466180 2400588176 S Bi:7:004:2 -115 13 < ffff880110466180 2400589164 C Bi:7:004:2 0 13 = 55534253 12000000 12000000 01 ffff8800c05bc9c0 2400589185 S Co:7:001:0 s 23 03 0004 0001 0000 0 ffff8800c05bc9c0 2400589190 C Co:7:001:0 0 0 ffff8800c05bc9c0 2400639775 S Ci:7:001:0 s a3 00 0000 0001 0004 4 < ffff8800c05bc9c0 2400639797 C Ci:7:001:0 0 4 = 03010000 ffff8800c05bc9c0 2400690757 S Co:7:001:0 s 23 01 0014 0001 0000 0 ffff8800c05bc9c0 2400690760 C Co:7:001:0 0 0 ffff8800c05bc9c0 2400690776 S Ci:7:000:0 s 80 06 0100 0000 0040 64 < ffff8800c05bc9c0 2400694164 C Ci:7:000:0 0 18 = 12010001 00000040 f3030120 00010102 0301 ffff8800c05bc9c0 2400694190 S Co:7:001:0 s 23 03 0004 0001 0000 0 ffff8800c05bc9c0 2400694195 C Co:7:001:0 0 0 ffff8800c05bc9c0 2400744776 S Ci:7:001:0 s a3 00 0000 0001 0004 4 < ffff8800c05bc9c0 2400744797 C Ci:7:001:0 0 4 = 03010000 ffff8800c05bc9c0 2400795758 S Co:7:001:0 s 23 01 0014 0001 0000 0 ffff8800c05bc9c0 2400795761 C Co:7:001:0 0 0 ffff8800c05bc9c0 2400795763 S Co:7:000:0 s 00 05 0004 0000 0000 0 ffff8800c05bc9c0 2400798165 C Co:7:000:0 0 0 ffff8800a5978380 2400810777 S Ci:7:004:0 s 80 06 0100 0000 0012 18 < ffff8800a5978380 2400814165 C Ci:7:004:0 0 18 = 12010001 00000040 f3030120 00010102 0301 ffff8800a5978380 2400814176 S Ci:7:004:0 s 80 06 0200 0000 0020 32 < ffff8800a5978380 2400818165 C Ci:7:004:0 0 32 = 09022000 01010480 fa090400 0002ff00 ff050705 82024000 00070502 02400000 ffff8800a5978380 2400818183 S Ci:7:004:0 s 80 06 0303 0409 00ff 255 < ffff8800a5978380 2400823165 C Ci:7:004:0 0 26 = 1a033100 30003000 30003000 30003100 32003800 36003700 3900 ffff8800a5978380 2400823182 S Co:7:004:0 s 00 09 0001 0000 0000 0 ffff8800a5978380 2400947165 C Co:7:004:0 0 0 ffff880110466180 2400948023 S Bo:7:004:2 -115 31 = 55534243 13000000 00000000 00000600 00000000 00000000 00000000 000000 ffff880110466180 2400949161 C Bo:7:004:2 0 31 > ffff880110466180 2400949167 S Bi:7:004:2 -115 13 < ffff880110466180 2401286169 C Bi:7:004:2 0 13 = 55534253 13000000 00000000 01 ffff880110466180 2401286183 S Bo:7:004:2 -115 31 = 55534243 14000000 12000000 80000603 00000012 00000000 00000000 000000 ffff880110466180 2401288171 C Bo:7:004:2 0 31 > ffff8800a0895980 2401288188 S Bi:7:004:2 -115 18 < ffff8800a0895980 2401624172 C Bi:7:004:2 -121 0 ffff880110466180 2401624184 S Bi:7:004:2 -115 13 < ffff880110466180 2401625174 C Bi:7:004:2 0 13 = 55534253 14000000 12000000 01 ffff8800a0895980 2401625195 S Co:7:001:0 s 23 03 0004 0001 0000 0 ffff8800a0895980 2401625200 C Co:7:001:0 0 0 ffff8801295aa140 2401658767 C Ii:7:001:1 0:128 1 = 02 ffff8801295aa140 2401658771 S Ii:7:001:1 -115:128 2 < ffff8800a0895980 2401675753 S Ci:7:001:0 s a3 00 0000 0001 0004 4 < ffff8800a0895980 2401675776 C Ci:7:001:0 0 4 = 03010000 ffff8800a0895980 2401726754 S Co:7:001:0 s 23 01 0014 0001 0000 0 ffff8800a0895980 2401726761 C Co:7:001:0 0 0 ffff8800a0895980 2401726777 S Ci:7:000:0 s 80 06 0100 0000 0040 64 < ffff8800a0895980 2401730175 C Ci:7:000:0 0 18 = 12010001 00000040 f3030120 00010102 0301 ffff8800a0895980 2401730190 S Co:7:001:0 s 23 03 0004 0001 0000 0 ffff8800a0895980 2401730195 C Co:7:001:0 0 0 ffff8800a0895980 2401780756 S Ci:7:001:0 s a3 00 0000 0001 0004 4 < ffff8800a0895980 2401780779 C Ci:7:001:0 0 4 = 03010000 ffff8800a0895980 2401831777 S Co:7:001:0 s 23 01 0014 0001 0000 0 ffff8800a0895980 2401831780 C Co:7:001:0 0 0 ffff8800a0895980 2401831783 S Co:7:000:0 s 00 05 0004 0000 0000 0 ffff8800a0895980 2401834174 C Co:7:000:0 0 0 ffff8800bd83f3c0 2401846755 S Ci:7:004:0 s 80 06 0100 0000 0012 18 < ffff8800bd83f3c0 2401850175 C Ci:7:004:0 0 18 = 12010001 00000040 f3030120 00010102 0301 ffff8800bd83f3c0 2401850190 S Ci:7:004:0 s 80 06 0200 0000 0020 32 < ffff8800bd83f3c0 2401854175 C Ci:7:004:0 0 32 = 09022000 01010480 fa090400 0002ff00 ff050705 82024000 00070502 02400000 ffff8800bd83f3c0 2401854191 S Ci:7:004:0 s 80 06 0303 0409 00ff 255 < ffff8800bd83f3c0 2401859175 C Ci:7:004:0 0 26 = 1a033100 30003000 30003000 30003100 32003800 36003700 3900 ffff8800bd83f3c0 2401859190 S Co:7:004:0 s 00 09 0001 0000 0000 0 ffff8800bd83f3c0 2401983177 C Co:7:004:0 0 0 ffff880110466180 2401983279 S Bo:7:004:2 -115 31 = 55534243 15000000 b0400000 80000a28 00000000 000040b0 00000000 000000 ffff880110466180 2401985176 C Bo:7:004:2 0 31 > ffff8800bd83f3c0 2401985187 S Bi:7:004:2 -115 16560 < ffff8800bd83f3c0 2402321219 C Bi:7:004:2 -121 0 ffff880110466180 2402321230 S Bi:7:004:2 -115 13 < ffff880110466180 2402322179 C Bi:7:004:2 0 13 = 55534253 15000000 b0400000 01 ffff880110466180 2402322195 S Bo:7:004:2 -115 31 = 55534243 16000000 12000000 80000603 00000012 00000000 00000000 000000 ffff880110466180 2402323178 C Bo:7:004:2 0 31 > ffff8800bd83f3c0 2402323194 S Bi:7:004:2 -115 18 < ffff8800bd83f3c0 2402658752 C Bi:7:004:2 -121 0 ffff880110466180 2402658787 S Bi:7:004:2 -115 13 < ffff880110466180 2402659181 C Bi:7:004:2 0 13 = 55534253 16000000 12000000 01 ffff8800bd83f3c0 2402659195 S Co:7:001:0 s 23 03 0004 0001 0000 0 ffff8800bd83f3c0 2402659201 C Co:7:001:0 0 0 ffff8800bd83f3c0 2402709773 S Ci:7:001:0 s a3 00 0000 0001 0004 4 < ffff8800bd83f3c0 2402709794 C Ci:7:001:0 0 4 = 03010000 ffff8800bd83f3c0 2402760758 S Co:7:001:0 s 23 01 0014 0001 0000 0 ffff8800bd83f3c0 2402760760 C Co:7:001:0 0 0 ffff8800bd83f3c0 2402760773 S Ci:7:000:0 s 80 06 0100 0000 0040 64 < ffff8800bd83f3c0 2402764184 C Ci:7:000:0 0 18 = 12010001 00000040 f3030120 00010102 0301 ffff8800bd83f3c0 2402764194 S Co:7:001:0 s 23 03 0004 0001 0000 0 ffff8800bd83f3c0 2402764199 C Co:7:001:0 0 0 ffff8800bd83f3c0 2402814773 S Ci:7:001:0 s a3 00 0000 0001 0004 4 < ffff8800bd83f3c0 2402814794 C Ci:7:001:0 0 4 = 03010000 ffff8800bd83f3c0 2402865758 S Co:7:001:0 s 23 01 0014 0001 0000 0 ffff8800bd83f3c0 2402865760 C Co:7:001:0 0 0 ffff8800bd83f3c0 2402865763 S Co:7:000:0 s 00 05 0004 0000 0000 0 ffff8800bd83f3c0 2402868185 C Co:7:000:0 0 0 ffff8800bd83f3c0 2402880753 S Ci:7:004:0 s 80 06 0100 0000 0012 18 < ffff8800bd83f3c0 2402884184 C Ci:7:004:0 0 18 = 12010001 00000040 f3030120 00010102 0301 ffff8800bd83f3c0 2402884202 S Ci:7:004:0 s 80 06 0200 0000 0020 32 < ffff8800bd83f3c0 2402888185 C Ci:7:004:0 0 32 = 09022000 01010480 fa090400 0002ff00 ff050705 82024000 00070502 02400000 ffff8800bd83f3c0 2402888202 S Ci:7:004:0 s 80 06 0303 0409 00ff 255 < ffff8800bd83f3c0 2402893186 C Ci:7:004:0 0 26 = 1a033100 30003000 30003000 30003100 32003800 36003700 3900 ffff8800bd83f3c0 2402893202 S Co:7:004:0 s 00 09 0001 0000 0000 0 ffff8800bd83f3c0 2403017186 C Co:7:004:0 0 0 ffff880110466180 2403017267 S Bo:7:004:2 -115 31 = 55534243 17000000 b0400000 80000a28 00000000 000040b0 00000000 000000 ffff880110466180 2403019188 C Bo:7:004:2 0 31 > ffff8800bd83f3c0 2403019204 S Bi:7:004:2 -115 16560 < ffff8800bd83f3c0 2403355229 C Bi:7:004:2 -121 0 ffff880110466180 2403355240 S Bi:7:004:2 -115 13 < ffff880110466180 2403356191 C Bi:7:004:2 0 13 = 55534253 17000000 b0400000 01 ffff880110466180 2403356207 S Bo:7:004:2 -115 31 = 55534243 18000000 12000000 80000603 00000012 00000000 00000000 000000 ffff880110466180 2403357188 C Bo:7:004:2 0 31 > ffff8800bd83f3c0 2403357203 S Bi:7:004:2 -115 18 < ffff8800bd83f3c0 2403694194 C Bi:7:004:2 -121 0 ffff880110466180 2403694205 S Bi:7:004:2 -115 13 < ffff880110466180 2403696194 C Bi:7:004:2 0 13 = 55534253 18000000 12000000 01 ffff8800bd83f3c0 2403696207 S Co:7:001:0 s 23 03 0004 0001 0000 0 ffff8800bd83f3c0 2403696213 C Co:7:001:0 0 0 ffff8800bd83f3c0 2403746759 S Ci:7:001:0 s a3 00 0000 0001 0004 4 < ffff8800bd83f3c0 2403746779 C Ci:7:001:0 0 4 = 03010000 ffff8800bd83f3c0 2403797758 S Co:7:001:0 s 23 01 0014 0001 0000 0 ffff8800bd83f3c0 2403797761 C Co:7:001:0 0 0 ffff8800bd83f3c0 2403797774 S Ci:7:000:0 s 80 06 0100 0000 0040 64 < ffff8800bd83f3c0 2403801194 C Ci:7:000:0 0 18 = 12010001 00000040 f3030120 00010102 0301 ffff8800bd83f3c0 2403801205 S Co:7:001:0 s 23 03 0004 0001 0000 0 ffff8800bd83f3c0 2403801209 C Co:7:001:0 0 0 ffff8800bd83f3c0 2403851759 S Ci:7:001:0 s a3 00 0000 0001 0004 4 < ffff8800bd83f3c0 2403851779 C Ci:7:001:0 0 4 = 03010000 ffff8800bd83f3c0 2403902786 S Co:7:001:0 s 23 01 0014 0001 0000 0 ffff8800bd83f3c0 2403902788 C Co:7:001:0 0 0 ffff8800bd83f3c0 2403902790 S Co:7:000:0 s 00 05 0004 0000 0000 0 ffff8800bd83f3c0 2403905195 C Co:7:000:0 0 0 ffff8800bd83f3c0 2403917760 S Ci:7:004:0 s 80 06 0100 0000 0012 18 < ffff8800bd83f3c0 2403921197 C Ci:7:004:0 0 18 = 12010001 00000040 f3030120 00010102 0301 ffff8800bd83f3c0 2403921208 S Ci:7:004:0 s 80 06 0200 0000 0020 32 < ffff8800bd83f3c0 2403925197 C Ci:7:004:0 0 32 = 09022000 01010480 fa090400 0002ff00 ff050705 82024000 00070502 02400000 ffff8800bd83f3c0 2403925213 S Ci:7:004:0 s 80 06 0303 0409 00ff 255 < ffff8800bd83f3c0 2403930196 C Ci:7:004:0 0 26 = 1a033100 30003000 30003000 30003100 32003800 36003700 3900 ffff8800bd83f3c0 2403930212 S Co:7:004:0 s 00 09 0001 0000 0000 0 ffff8800bd83f3c0 2404054198 C Co:7:004:0 0 0 ffff880110466180 2524185203 S Bo:7:004:2 -115 31 = 55534243 19000000 00000000 00000617 00000000 00000000 00000000 000000 ffff880110466180 2524186247 C Bo:7:004:2 0 31 > ffff880110466180 2524186256 S Bi:7:004:2 -115 13 < ffff880110466180 2524190246 C Bi:7:004:2 0 13 = 55534253 19000000 00000000 00 ffff880110466180 2524190294 S Bo:7:004:2 -115 31 = 55534243 1a000000 00000000 00000a31 00000000 00000000 00000000 000000 ffff880110466180 2524191246 C Bo:7:004:2 0 31 > ffff880110466180 2524191254 S Bi:7:004:2 -115 13 < ffff880110466180 2524196250 C Bi:7:004:2 0 13 = 55534253 1a000000 00000000 00 ________________________________________________________________________ Stanislav Brabec http://www.penguin.cz/~utx -- To unsubscribe from this list: send the line "unsubscribe linux-usb" in the body of a message to majordomo@xxxxxxxxxxxxxxx More majordomo info at http://vger.kernel.org/majordomo-info.html
