kernel-packages team mailing list archive

Thread
Date
[Bug 1437492] Re: boot stalls on USB detection errors

To: kernel-packages@xxxxxxxxxxxxxxxxxxx
From: Jeff <jdgspam4me@xxxxxxxxx>
Date: Sat, 25 Jul 2015 21:00:24 -0000
Reply-to: Bug 1437492 <1437492@xxxxxxxxxxxxxxxxxx>
Sender: bounces@xxxxxxxxxxxxx
Ubuntu 15.04 - Not just slow, also doesn't recognize one of the USB devices....
Similar to what others reported, except after usb errors and insanely slow boot time, my Kinesis Advantage PRO MPC/USB Keyboard (which has a USB hub) doesn't work. The lsusb output shows that the hub is recognized, but the keyboard is not.
After boot, I have to unplug the keyboard, wait a few seconds, then plug in the keyboard again in order for it to be recognized.

Motherboard is similar to others affected in this bug, ASUS Z87 series
[    0.000000] DMI: ASUS All Series/SABERTOOTH Z87, BIOS 2103 08/18/2014

Below is usb information from the following circumstances:

1.    'dmesg|grep -i usb'    Immediately after boot completed
2.    'lsusb'                Immediately after boot completed
3.    Unplug Kinesis keyboard, additional output from 'dmesg|grep -i usb'
4.    Plug Kinesis keyboard back in, additional output from 'dmesg|grep -i usb'
5.    'lsusb' output at this point
6.    'systemd-analyze blame'
7.    'uname -a'

Immediately after boot:
$ dmesg|grep -i usb
[    0.220777] ACPI: bus type USB registered
[    0.220786] usbcore: registered new interface driver usbfs
[    0.220791] usbcore: registered new interface driver hub
[    0.220802] usbcore: registered new device driver usb
[    0.478834] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1
[    0.478954] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    0.478955] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.478956] usb usb1: Product: xHCI Host Controller
[    0.478956] usb usb1: Manufacturer: Linux 3.19.0-23-generic xhci-hcd
[    0.478957] usb usb1: SerialNumber: 0000:00:14.0
[    0.479019] hub 1-0:1.0: USB hub found
[    0.481098] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2
[    0.481119] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[    0.481119] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.481120] usb usb2: Product: xHCI Host Controller
[    0.481121] usb usb2: Manufacturer: Linux 3.19.0-23-generic xhci-hcd
[    0.481122] usb usb2: SerialNumber: 0000:00:14.0
[    0.481176] hub 2-0:1.0: USB hub found
[    0.481831] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.481888] ehci-pci 0000:00:1a.0: new USB bus registered, assigned bus number 3
[    0.497717] ehci-pci 0000:00:1a.0: USB 2.0 started, EHCI 1.00
[    0.497733] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002
[    0.497734] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.497735] usb usb3: Product: EHCI Host Controller
[    0.497736] usb usb3: Manufacturer: Linux 3.19.0-23-generic ehci_hcd
[    0.497737] usb usb3: SerialNumber: 0000:00:1a.0
[    0.497808] hub 3-0:1.0: USB hub found
[    0.497922] ehci-pci 0000:00:1d.0: new USB bus registered, assigned bus number 4
[    0.513729] ehci-pci 0000:00:1d.0: USB 2.0 started, EHCI 1.00
[    0.513749] usb usb4: New USB device found, idVendor=1d6b, idProduct=0002
[    0.513750] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    0.513751] usb usb4: Product: EHCI Host Controller
[    0.513751] usb usb4: Manufacturer: Linux 3.19.0-23-generic ehci_hcd
[    0.513752] usb usb4: SerialNumber: 0000:00:1d.0
[    0.513823] hub 4-0:1.0: USB hub found
[    0.513892] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.513905] uhci_hcd: USB Universal Host Controller Interface driver
[    0.789994] usb 1-5: new high-speed USB device number 2 using xhci_hcd
[    0.814026] usb 3-1: new high-speed USB device number 2 using ehci-pci
[    0.830076] usb 4-1: new high-speed USB device number 2 using ehci-pci
[    0.946494] usb 3-1: New USB device found, idVendor=8087, idProduct=8008
[    0.946497] usb 3-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    0.946639] hub 3-1:1.0: USB hub found
[    0.962499] usb 4-1: New USB device found, idVendor=8087, idProduct=8000
[    0.962500] usb 4-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    0.962672] hub 4-1:1.0: USB hub found
[   11.227227] usb 2-5: device not accepting address 2, error -62
[   16.139583] usb 1-5: device descriptor read/64, error -110
[   31.369047] usb 1-5: device descriptor read/64, error -110
[   31.585233] usb 1-5: new high-speed USB device number 3 using xhci_hcd
[   46.710610] usb 1-5: device descriptor read/64, error -110
[   61.940072] usb 1-5: device descriptor read/64, error -110
[   62.156258] usb 1-5: new high-speed USB device number 4 using xhci_hcd
[   72.593488] usb 1-5: device not accepting address 4, error -62
[   72.705585] usb 1-5: new high-speed USB device number 5 using xhci_hcd
[   83.146811] usb 1-5: device not accepting address 5, error -62
[   83.146822] usb usb1-port5: unable to enumerate USB device
[   83.258915] usb 1-6: new full-speed USB device number 6 using xhci_hcd
[   83.388138] usb 1-6: New USB device found, idVendor=1e7d, idProduct=2d51
[   83.388140] usb 1-6: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[   83.388141] usb 1-6: Product: ROCCAT Kone[+]
[   83.388142] usb 1-6: Manufacturer: ROCCAT
[   83.555178] usb 1-7: new low-speed USB device number 7 using xhci_hcd
[   83.688149] usb 1-7: New USB device found, idVendor=0e8f, idProduct=0022
[   83.688161] usb 1-7: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[   83.688163] usb 1-7: Product: USB KB V11
[   83.688164] usb 1-7: Manufacturer: GASIA
[   83.688298] usb 1-7: ep 0x81 - rounding interval to 64 microframes, ep desc says 80 microframes
[   83.688301] usb 1-7: ep 0x82 - rounding interval to 64 microframes, ep desc says 80 microframes
[   83.855442] usb 1-14: new full-speed USB device number 8 using xhci_hcd
[   83.985689] usb 1-14: New USB device found, idVendor=05f3, idProduct=0081
[   83.985702] usb 1-14: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[   83.985703] usb 1-14: Product: Kinesis Keyboard Hub
[   83.985704] usb 1-14: Manufacturer: PI Engineering
[   83.985837] usb 1-14: ep 0x81 - rounding interval to 1024 microframes, ep desc says 2040 microframes
[   83.986268] hub 1-14:1.0: USB hub found
[   84.029515] usbcore: registered new interface driver usbhid
[   84.029516] usbhid: USB HID core driver
[   84.031471] input: GASIA USB KB V11 as /devices/pci0000:00/0000:00:14.0/usb1/1-7/1-7:1.0/0003:0E8F:0022.0003/input/input14
[   84.033704] input: ROCCAT ROCCAT Kone[+] as /devices/pci0000:00/0000:00:14.0/usb1/1-6/1-6:1.0/0003:1E7D:2D51.0001/input/input15
[   84.083689] hid-generic 0003:0E8F:0022.0003: input,hidraw0: USB HID v1.10 Keyboard [GASIA USB KB V11] on usb-0000:00:14.0-7/input0
[   84.084548] input: GASIA USB KB V11 as /devices/pci0000:00/0000:00:14.0/usb1/1-7/1-7:1.1/0003:0E8F:0022.0004/input/input16
[   84.139958] koneplus 0003:1E7D:2D51.0001: input,hiddev0,hidraw1: USB HID v1.00 Mouse [ROCCAT ROCCAT Kone[+]] on usb-0000:00:14.0-6/input0
[   84.140247] input: ROCCAT ROCCAT Kone[+] as /devices/pci0000:00/0000:00:14.0/usb1/1-6/1-6:1.1/0003:1E7D:2D51.0002/input/input17
[   84.195924] hid-generic 0003:0E8F:0022.0004: input,hidraw2: USB HID v1.10 Device [GASIA USB KB V11] on usb-0000:00:14.0-7/input1
[   84.251824] koneplus 0003:1E7D:2D51.0002: input,hidraw3: USB HID v1.11 Keyboard [ROCCAT ROCCAT Kone[+]] on usb-0000:00:14.0-6/input1


At this point, lsusb output is:
$ lsusb
Bus 004 Device 002: ID 8087:8000 Intel Corp. 
Bus 004 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 002: ID 8087:8008 Intel Corp. 
Bus 003 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 001 Device 007: ID 0e8f:0022 GreenAsia Inc. 
Bus 001 Device 006: ID 1e7d:2d51 ROCCAT Kone+ Mouse
Bus 001 Device 008: ID 05f3:0081 PI Engineering, Inc. Kinesis Integrated Hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub


At this point, I unplug the Kinesis keyboard, as shown in dmesg:
[  748.907560] usb 1-14: USB disconnect, device number 8

Then, when I re-plug in the keyboard, dmesg shows:
[  860.263930] usb 1-14: new full-speed USB device number 9 using xhci_hcd
[  860.394296] usb 1-14: New USB device found, idVendor=05f3, idProduct=0081
[  860.394304] usb 1-14: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[  860.394308] usb 1-14: Product: Kinesis Keyboard Hub
[  860.394312] usb 1-14: Manufacturer: PI Engineering
[  860.394537] usb 1-14: ep 0x81 - rounding interval to 1024 microframes, ep desc says 2040 microframes
[  860.395060] hub 1-14:1.0: USB hub found
[  860.668562] usb 1-14.2: new full-speed USB device number 10 using xhci_hcd
[  860.768238] usb 1-14.2: New USB device found, idVendor=05f3, idProduct=0007
[  860.768240] usb 1-14.2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[  860.772206] input: HID 05f3:0007 as /devices/pci0000:00/0000:00:14.0/usb1/1-14/1-14.2/1-14.2:1.0/0003:05F3:0007.0005/input/input18
[  860.824489] hid-generic 0003:05F3:0007.0005: input,hidraw4: USB HID v1.00 Keyboard [HID 05f3:0007] on usb-0000:00:14.0-14.2/input0
[  860.829515] input: HID 05f3:0007 as /devices/pci0000:00/0000:00:14.0/usb1/1-14/1-14.2/1-14.2:1.1/0003:05F3:0007.0006/input/input19
[  860.884536] hid-generic 0003:05F3:0007.0006: input,hidraw5: USB HID v1.00 Device [HID 05f3:0007] on usb-0000:00:14.0-14.2/input1

And now the lsusb output shows the Kinesis keyboard as well as the hub:
$ lsusb
Bus 004 Device 002: ID 8087:8000 Intel Corp. 
Bus 004 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 002: ID 8087:8008 Intel Corp. 
Bus 003 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 001 Device 007: ID 0e8f:0022 GreenAsia Inc. 
Bus 001 Device 006: ID 1e7d:2d51 ROCCAT Kone+ Mouse
Bus 001 Device 010: ID 05f3:0007 PI Engineering, Inc. Kinesis Advantage PRO MPC/USB Keyboard
Bus 001 Device 009: ID 05f3:0081 PI Engineering, Inc. Kinesis Integrated Hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub

systemd-analyze blame
    1min 22.455s systemd-udev-settle.service

$ uname -a
Linux notmyhostname 3.19.0-23-generic #24-Ubuntu SMP Tue Jul 7 18:52:55 UTC 2015 x86_64 x86_64 x86_64 GNU/Linux

-- 
You received this bug notification because you are a member of Kernel
Packages, which is subscribed to linux in Ubuntu.
https://bugs.launchpad.net/bugs/1437492

Title:
  boot stalls on USB detection errors

Status in linux package in Ubuntu:
  Confirmed

Bug description:
  My system boots slow. I investigated this and it seems systemd-udev-settle.service is the culprit.
  My system runs ext4 with GPT on UEFI.
  I do not use encryption or LVM.

  $ journalctl -u systemd-udev-settle
  -- Logs begin at fri 2015-03-27 19:03:06 CET, end at fri 2015-03-27 22:06:32 CET. --
  mar 27 19:03:42 hostname systemd[1]: Started udev Wait for Complete Device Initialization.

  $ systemd-analyze
  Startup finished in 14.865s (firmware) + 11.133s (loader) + 6.127s (kernel) + 42.079s (userspace) = 1min 14.206s

  $ systemd-analyze critical-chain
  http://paste.ubuntu.com/10691416/

  $ systemd-analyze blame
  36.013s systemd-udev-settle.service
  http://paste.ubuntu.com/10691314/

  $ systemctl show systemd-udev-settle.service -p RequiredBy
  RequiredBy=

  $ systemctl show systemd-udev-settle.service -p WantedBy
  WantedBy=friendly-recovery.service

  $ systemd-analyze plot > boot.svg
  http://imgh.us/boot_1.svg

  $ systemd-analyze dump
  http://paste.ubuntu.com/10691856/

  ProblemType: Bug
  DistroRelease: Ubuntu 15.04
  Package: systemd 219-5ubuntu1
  ProcVersionSignature: Ubuntu 3.19.0-10.10-generic 3.19.2
  Uname: Linux 3.19.0-10-generic x86_64
  ApportVersion: 2.16.2-0ubuntu5
  Architecture: amd64
  CurrentDesktop: GNOME-Flashback:Unity
  Date: Fri Mar 27 22:05:28 2015
  InstallationDate: Installed on 2013-12-26 (455 days ago)
  InstallationMedia: Ubuntu 13.10 "Saucy Salamander" - Release amd64 (20131016.1)
  MachineType: ASUS All Series
  ProcKernelCmdLine: BOOT_IMAGE=/boot/vmlinuz-3.19.0-10-generic.efi.signed root=UUID=31dc4488-28d4-4d2a-aa51-6733e237d5f8 ro quiet splash vt.handoff=7
  SourcePackage: systemd
  UpgradeStatus: No upgrade log present (probably fresh install)
  dmi.bios.date: 08/18/2014
  dmi.bios.vendor: American Megatrends Inc.
  dmi.bios.version: 2103
  dmi.board.asset.tag: To be filled by O.E.M.
  dmi.board.name: Z87-PRO
  dmi.board.vendor: ASUSTeK COMPUTER INC.
  dmi.board.version: Rev 1.xx
  dmi.chassis.asset.tag: Asset-1234567890
  dmi.chassis.type: 3
  dmi.chassis.vendor: Chassis Manufacture
  dmi.chassis.version: Chassis Version
  dmi.modalias: dmi:bvnAmericanMegatrendsInc.:bvr2103:bd08/18/2014:svnASUS:pnAllSeries:pvrSystemVersion:rvnASUSTeKCOMPUTERINC.:rnZ87-PRO:rvrRev1.xx:cvnChassisManufacture:ct3:cvrChassisVersion:
  dmi.product.name: All Series
  dmi.product.version: System Version
  dmi.sys.vendor: ASUS

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/linux/+bug/1437492/+subscriptions