[sane-devel] Scanner failure when connected via USB3

Mike Cloaked mike.cloaked at gmail.com
Wed Oct 29 20:01:38 UTC 2014


I have run some more tests this evening - after starting the wireshark
program, I set the systemd journal to log with -f to watch output, and
first simply plugged in the scanner.

Journal log gave:
ct 29 19:39:51 lenovo2 kernel: usb 3-4: new high-speed USB device number 4
using xhci_hcd
Oct 29 19:39:51 lenovo2 kernel: WARNING! power/level is deprecated; use
power/control instead

The wireshark usbmonitor file ( replayable as input to wireshark), and text
packet dissector files are attached.

At this stage xsane was not started, and only the hardware was plugged in.

I also note that the arch linux wiki on Sane mentions:
You may also get this error loged while attempting to scan:
kernel: usb 1-2: new high-speed USB device number 8 using xhci_hcd
kernel: WARNING! power/level is deprecated; use power/control instead
The fix is: In the UEFI/BIOS change the setting under USB configuration,
xhci pre-boot mode from enabled to disabled.

This implies that usb3 is problematic and switches off fast usb3 if xhci
pre-boot mode was disabled!

As soon as I started xsane, it appeared to start normally and presented the
gui to "Acquire Preview".  As soon as I clicked to acquire preview the
scanner screamed and xsane hung. I will attach the logs fro wireshark for
this second stage in a separate email shortly.  However I left the system
to itself and after some 10 seconds or so it stopped screaming and
presented a small popup dialogue box saying that the device was not found.

At that point I also noticed that the journal log showed:

Oct 29 19:41:22 lenovo2 kernel: mce: [Hardware Error]: Machine check events
logged

I'll now attach the wireshark logs as they should be small enough to be
accepted in this ML.

-- 
mike c
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.alioth.debian.org/pipermail/sane-devel/attachments/20141029/aed86100/attachment-0001.html>
-------------- next part --------------
      1 0.000000000    1.1                   host                  USB      66     URB_INTERRUPT in

Frame 1: 66 bytes on wire (528 bits), 66 bytes captured (528 bits) on interface 2
USB URB
Leftover Capture Data: 1000

      2 0.000008000    host                  1.1                   USB      64     URB_INTERRUPT in

Frame 2: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB

      3 0.000029000    host                  1.0                   USBHUB   64     GET_STATUS Request     [Port 4]

Frame 3: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: GET_STATUS (0x00)
wValue: 0x0000
wIndex: 4
wLength: 4

      4 0.000038000    1.0                   host                  USBHUB   68     GET_STATUS Response    [Port 4]

Frame 4: 68 bytes on wire (544 bits), 68 bytes captured (544 bits) on interface 2
USB URB
Port Status: 0x0101, PORT_CONNECTION, PORT_POWER
Port Change: 0x0001, C_PORT_CONNECTION

      5 0.000064000    host                  1.0                   USBHUB   64     CLEAR_FEATURE Request  [Port 4: C_PORT_CONNECTION]

Frame 5: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: CLEAR_FEATURE (0x01)
wValue: 0x0010
wIndex: 4
wLength: 0

      6 0.000073000    1.0                   host                  USB      64     URB_CONTROL out

Frame 6: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB

      7 0.000095000    host                  1.0                   USBHUB   64     GET_STATUS Request     [Port 4]

Frame 7: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: GET_STATUS (0x00)
wValue: 0x0000
wIndex: 4
wLength: 4

      8 0.000103000    1.0                   host                  USBHUB   68     GET_STATUS Response    [Port 4]

Frame 8: 68 bytes on wire (544 bits), 68 bytes captured (544 bits) on interface 2
USB URB
Port Status: 0x0101, PORT_CONNECTION, PORT_POWER
Port Change: 0x0000

      9 0.028007000    host                  1.0                   USBHUB   64     GET_STATUS Request     [Port 4]

Frame 9: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: GET_STATUS (0x00)
wValue: 0x0000
wIndex: 4
wLength: 4

     10 0.028019000    1.0                   host                  USBHUB   68     GET_STATUS Response    [Port 4]

Frame 10: 68 bytes on wire (544 bits), 68 bytes captured (544 bits) on interface 2
USB URB
Port Status: 0x0101, PORT_CONNECTION, PORT_POWER
Port Change: 0x0000

     11 0.058008000    host                  1.0                   USBHUB   64     GET_STATUS Request     [Port 4]

Frame 11: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: GET_STATUS (0x00)
wValue: 0x0000
wIndex: 4
wLength: 4

     12 0.058020000    1.0                   host                  USBHUB   68     GET_STATUS Response    [Port 4]

Frame 12: 68 bytes on wire (544 bits), 68 bytes captured (544 bits) on interface 2
USB URB
Port Status: 0x0101, PORT_CONNECTION, PORT_POWER
Port Change: 0x0000

     13 0.088006000    host                  1.0                   USBHUB   64     GET_STATUS Request     [Port 4]

Frame 13: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: GET_STATUS (0x00)
wValue: 0x0000
wIndex: 4
wLength: 4

     14 0.088018000    1.0                   host                  USBHUB   68     GET_STATUS Response    [Port 4]

Frame 14: 68 bytes on wire (544 bits), 68 bytes captured (544 bits) on interface 2
USB URB
Port Status: 0x0101, PORT_CONNECTION, PORT_POWER
Port Change: 0x0000

     15 0.118009000    host                  1.0                   USBHUB   64     GET_STATUS Request     [Port 4]

Frame 15: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: GET_STATUS (0x00)
wValue: 0x0000
wIndex: 4
wLength: 4

     16 0.118021000    1.0                   host                  USBHUB   68     GET_STATUS Response    [Port 4]

Frame 16: 68 bytes on wire (544 bits), 68 bytes captured (544 bits) on interface 2
USB URB
Port Status: 0x0101, PORT_CONNECTION, PORT_POWER
Port Change: 0x0000

     17 0.118090000    host                  1.0                   USBHUB   64     SET_FEATURE Request    [Port 4: PORT_RESET]

Frame 17: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: SET_FEATURE (0x03)
wValue: 0x0004
wIndex: 4
wLength: 0

     18 0.118101000    1.0                   host                  USB      64     URB_CONTROL out

Frame 18: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB

     19 0.171342000    host                  1.0                   USBHUB   64     GET_STATUS Request     [Port 4]

Frame 19: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: GET_STATUS (0x00)
wValue: 0x0000
wIndex: 4
wLength: 4

     20 0.171354000    1.0                   host                  USBHUB   68     GET_STATUS Response    [Port 4]

Frame 20: 68 bytes on wire (544 bits), 68 bytes captured (544 bits) on interface 2
USB URB
Port Status: 0x0511, PORT_CONNECTION, PORT_RESET, PORT_POWER, PORT_HIGH_SPEED
Port Change: 0x0000

     21 0.224675000    host                  1.0                   USBHUB   64     GET_STATUS Request     [Port 4]

Frame 21: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: GET_STATUS (0x00)
wValue: 0x0000
wIndex: 4
wLength: 4

     22 0.224688000    1.0                   host                  USBHUB   68     GET_STATUS Response    [Port 4]

Frame 22: 68 bytes on wire (544 bits), 68 bytes captured (544 bits) on interface 2
USB URB
Port Status: 0x0503, PORT_CONNECTION, PORT_ENABLE, PORT_POWER, PORT_HIGH_SPEED
Port Change: 0x0010, C_PORT_RESET

     23 0.278005000    host                  1.0                   USBHUB   64     CLEAR_FEATURE Request  [Port 4: C_PORT_RESET]

Frame 23: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: CLEAR_FEATURE (0x01)
wValue: 0x0014
wIndex: 4
wLength: 0

     24 0.278017000    1.0                   host                  USB      64     URB_CONTROL out

Frame 24: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB

     25 0.278079000    host                  0.0                   USB      64     GET DESCRIPTOR Request DEVICE

Frame 25: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup

     26 0.278304000    0.0                   host                  USB      82     GET DESCRIPTOR Response DEVICE

Frame 26: 82 bytes on wire (656 bits), 82 bytes captured (656 bits) on interface 2
USB URB
DEVICE DESCRIPTOR

     27 0.278322000    host                  1.0                   USBHUB   64     SET_FEATURE Request    [Port 4: PORT_RESET]

Frame 27: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: SET_FEATURE (0x03)
wValue: 0x0004
wIndex: 4
wLength: 0

     28 0.278334000    1.0                   host                  USB      64     URB_CONTROL out

Frame 28: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB

     29 0.331338000    host                  1.0                   USBHUB   64     GET_STATUS Request     [Port 4]

Frame 29: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: GET_STATUS (0x00)
wValue: 0x0000
wIndex: 4
wLength: 4

     30 0.331351000    1.0                   host                  USBHUB   68     GET_STATUS Response    [Port 4]

Frame 30: 68 bytes on wire (544 bits), 68 bytes captured (544 bits) on interface 2
USB URB
Port Status: 0x0511, PORT_CONNECTION, PORT_RESET, PORT_POWER, PORT_HIGH_SPEED
Port Change: 0x0000

     31 0.384675000    host                  1.0                   USBHUB   64     GET_STATUS Request     [Port 4]

Frame 31: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: GET_STATUS (0x00)
wValue: 0x0000
wIndex: 4
wLength: 4

     32 0.384687000    1.0                   host                  USBHUB   68     GET_STATUS Response    [Port 4]

Frame 32: 68 bytes on wire (544 bits), 68 bytes captured (544 bits) on interface 2
USB URB
Port Status: 0x0503, PORT_CONNECTION, PORT_ENABLE, PORT_POWER, PORT_HIGH_SPEED
Port Change: 0x0010, C_PORT_RESET

     33 0.437997000    host                  1.0                   USBHUB   64     CLEAR_FEATURE Request  [Port 4: C_PORT_RESET]

Frame 33: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup
bRequest: CLEAR_FEATURE (0x01)
wValue: 0x0014
wIndex: 4
wLength: 0

     34 0.438010000    1.0                   host                  USB      64     URB_CONTROL out

Frame 34: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB

     35 0.451342000    host                  4.0                   USB      64     GET DESCRIPTOR Request DEVICE

Frame 35: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup

     36 0.451532000    4.0                   host                  USB      82     GET DESCRIPTOR Response DEVICE

Frame 36: 82 bytes on wire (656 bits), 82 bytes captured (656 bits) on interface 2
USB URB
DEVICE DESCRIPTOR

     37 0.451559000    host                  4.0                   USB      64     GET DESCRIPTOR Request CONFIGURATION

Frame 37: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup

     38 0.451792000    4.0                   host                  USB      73     GET DESCRIPTOR Response CONFIGURATION

Frame 38: 73 bytes on wire (584 bits), 73 bytes captured (584 bits) on interface 2
USB URB
CONFIGURATION DESCRIPTOR

     39 0.451820000    host                  4.0                   USB      64     GET DESCRIPTOR Request CONFIGURATION

Frame 39: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup

     40 0.452116000    4.0                   host                  USB      103    GET DESCRIPTOR Response CONFIGURATION

Frame 40: 103 bytes on wire (824 bits), 103 bytes captured (824 bits) on interface 2
USB URB
CONFIGURATION DESCRIPTOR
INTERFACE DESCRIPTOR (0.0): class Vendor Specific
ENDPOINT DESCRIPTOR
ENDPOINT DESCRIPTOR
ENDPOINT DESCRIPTOR

     41 0.452147000    host                  4.0                   USB      64     GET DESCRIPTOR Request STRING

Frame 41: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup

     42 0.452418000    4.0                   host                  USB      68     GET DESCRIPTOR Response STRING

Frame 42: 68 bytes on wire (544 bits), 68 bytes captured (544 bits) on interface 2
USB URB
STRING DESCRIPTOR

     43 0.452446000    host                  4.0                   USB      64     GET DESCRIPTOR Request STRING

Frame 43: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup

     44 0.452726000    4.0                   host                  USB      82     GET DESCRIPTOR Response STRING

Frame 44: 82 bytes on wire (656 bits), 82 bytes captured (656 bits) on interface 2
USB URB
STRING DESCRIPTOR

     45 0.452755000    host                  4.0                   USB      64     GET DESCRIPTOR Request STRING

Frame 45: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup

     46 0.453027000    4.0                   host                  USB      76     GET DESCRIPTOR Response STRING

Frame 46: 76 bytes on wire (608 bits), 76 bytes captured (608 bits) on interface 2
USB URB
STRING DESCRIPTOR

     47 0.453420000    host                  4.0                   USB      64     SET CONFIGURATION Request

Frame 47: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
URB setup

     48 0.453767000    4.0                   host                  USB      64     SET CONFIGURATION Response

Frame 48: 64 bytes on wire (512 bits), 64 bytes captured (512 bits) on interface 2
USB URB
-------------- next part --------------
A non-text attachment was scrubbed...
Name: wireshark-plug-scanner-1.pcapng
Type: application/octet-stream
Size: 5592 bytes
Desc: not available
URL: <http://lists.alioth.debian.org/pipermail/sane-devel/attachments/20141029/aed86100/attachment-0001.obj>


More information about the sane-devel mailing list