Bug #1454450 “Bluetooth mouse laggy and erratic” : Bugs : linux-firmware package : Ubuntu

Revision history for this message

Francisco Cribari (cribari) wrote on 2015-05-12:

#1

CurrentDmesg.txt Edit (123.6 KiB, text/plain; charset="utf-8")
Dependencies.txt Edit (3.5 KiB, text/plain; charset="utf-8")
JournalErrors.txt Edit (59.9 KiB, text/plain; charset="utf-8")
Lspci.txt Edit (21.2 KiB, text/plain; charset="utf-8")
Lsusb.txt Edit (388 bytes, text/plain; charset="utf-8")
ProcCpuinfo.txt Edit (4.1 KiB, text/plain; charset="utf-8")
ProcEnviron.txt Edit (252 bytes, text/plain; charset="utf-8")
ProcInterrupts.txt Edit (2.7 KiB, text/plain; charset="utf-8")
ProcMaps.txt Edit (4.5 KiB, text/plain; charset="utf-8")
ProcModules.txt Edit (6.3 KiB, text/plain; charset="utf-8")
ProcStatus.txt Edit (775 bytes, text/plain; charset="utf-8")
UdevDb.txt Edit (172.8 KiB, text/plain; charset="utf-8")
UdevLog.txt Edit (409.4 KiB, text/plain; charset="utf-8")
getfacl.txt Edit (160 bytes, text/plain; charset="utf-8")
rfkill.txt Edit (174 bytes, text/plain; charset="utf-8")
syslog.txt Edit (20.1 KiB, text/plain; charset="utf-8")

summary:

- Bluetooth mouse laggy and erractic
+ Bluetooth mouse laggy and erratic

Revision history for this message

Seth Arnold (seth-arnold) wrote on 2015-05-12: Bug is not a security issue

#2

Thanks for taking the time to report this bug and helping to make Ubuntu better. We appreciate the difficulties you are facing, but this appears to be a "regular" (non-security) bug. I have unmarked it as a security issue since this bug does not show evidence of allowing attackers to cross privilege boundaries nor directly cause loss of data/privacy. Please feel free to report any other bugs you may find.

information type:

Private Security → Public

Francisco Cribari (cribari) on 2015-05-13

description:	updated
description:	updated

Revision history for this message

Shih-Yuan Lee (fourdollars) wrote on 2015-05-19:

#3

I noticed the following two lines.

[ 9442.157844] bluetooth hci0: firmware: brcm/BCM20702A0-0a5c-216f.hcd will not be loaded
[ 9442.157846] Bluetooth: hci0: BCM: patch brcm/BCM20702A0-0a5c-216f.hcd not found

Did you try the firmware as https://wiki.archlinux.org/index.php/Dell_XPS_13_%282015%29#Bluetooth mentioned.

Revision history for this message

Shih-Yuan Lee (fourdollars) wrote on 2015-05-19:

#4

http://askubuntu.com/questions/613614/bluetooth-not-working-in-ubuntu-15-04 also mentioned this firmware.

Revision history for this message

Shih-Yuan Lee (fourdollars) wrote on 2015-05-19:

#5

https://askubuntu.com/questions/617513/bluetooth-not-connecting-to-devices-even-though-it-recognizes-them also.

Keng-Yu Lin (lexical) on 2015-05-19

description:

updated

Keng-Yu Lin (lexical) on 2015-05-19

description:

updated

Keng-Yu Lin (lexical) on 2015-05-19

Changed in hwe-next:
assignee:	nobody → Adam Lee (adam8157)
status:	New → Triaged
importance:	Undecided → High

Revision history for this message

Francisco Cribari (cribari) wrote on 2015-05-19:

#6

I followed the instructions available here:

http://askubuntu.com/questions/613614/bluetooth-not-working-in-ubuntu-15-04

That is, I did:

wget https://www.dropbox.com/s/r2pb41rhx65t9zi/BCM20702A0-0a5c-216f.hcd
sudo cp BCM20702A0-0a5c-216f.hcd /lib/firmware/brcm/
sudo modprobe -r btusb
sudo modprobe btusb

Revision history for this message

Shih-Yuan Lee (fourdollars) wrote on 2015-05-19:

#7

@cribari: What is the result?

Revision history for this message

Francisco Cribari (cribari) wrote on 2015-05-19:

#8

@fourdollars I did that when I first installed Ubuntu 15.04 on my new DELL XPS 13 (2015 edition, model 9343, bios A03). I was then able to pair devices via bluetooth. Nonetheless, I faced two major problems after that: 1) my bluetooth mouse became laggy and erratic after a suspend (to RAM) and resume (both DELL and Microsoft mice). 2) frequent freezes/crashes when I tried to suspend (blank screen, CAPS LOCK key blinking, unable to ssh into the machine). Last night I did a fresh reinstall of Ubuntu 15.04.

Adam Lee (adam8157) on 2015-05-20

Changed in hwe-next:
assignee:	Adam Lee (adam8157) → nobody
assignee:	nobody → Keng-Yu Lin (lexical)

Revision history for this message

Francisco Cribari (cribari) wrote on 2015-05-22:

#9

Download full text (8.3 KiB)

The problem just happened to me again. I am including bellow the dmesg information.

[39935.550219] ACPI: Waking up from system sleep state S3
[39936.749233] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
[39936.749486] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
[39936.749535] PM: noirq resume of devices complete after 16.818 msecs
[39936.772659] PM: early resume of devices complete after 23.055 msecs
[39936.772771] usb usb1: root hub lost power or was reset
[39936.772772] usb usb2: root hub lost power or was reset
[39936.773878] sd 3:0:0:0: [sda] Starting disk
[39936.774743] rtc_cmos 00:01: System wakeup disabled by ACPI
[39936.957171] cfg80211: Calling CRDA to update world regulatory domain
[39937.000773] usb 3-1: reset high-speed USB device number 2 using ehci-pci
[39937.096852] ata4: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[39937.098948] ata4.00: failed to get NCQ Send/Recv Log Emask 0x1
[39937.099202] ata4.00: failed to get NCQ Send/Recv Log Emask 0x1
[39937.099240] ata4.00: configured for UDMA/133
[39937.277116] usb 1-3: reset full-speed USB device number 2 using xhci_hcd
[39937.406403] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e00
[39937.406404] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e90
[39937.406405] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e48
[39937.406412] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979948
[39937.406413] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979900
[39937.406413] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979288
[39937.406414] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979240
[39937.517280] usb 1-4: reset full-speed USB device number 3 using xhci_hcd
[39937.645962] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d90cd3c0
[39937.645963] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d90cd408
[39937.645976] usb 1-4: ep 0x2 - rounding interval to 64 microframes, ep desc says 80 microframes
[39937.757453] usb 1-5: reset high-speed USB device number 4 using xhci_hcd
[39937.995391] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d8fb2f60
[39937.995398] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d8fb2f00
[39938.038325] PM: resume of devices complete after 1264.639 msecs
[39938.038849] ------------[ cut here ]------------
[39938.038853] WARNING: CPU: 1 PID: 8500 at /build/buildd/linux-3.19.0/drivers/base/firmware_class.c:1126 _request_firmware+0x72e/0xbd0()
[39938.038877] Modules linked in: cpuid mmc_block ses enclosure uas usb_storage hid_generic hidp binfmt_misc rfcomm bnep nls_iso8859_1 uvcvideo videobuf2_vmalloc videobuf2_memops btusb videobuf2_core bluetooth v4l2_common videodev usbhid media dell_led joydev dell_wmi hid_multitouch sparse_keymap dell_laptop dcdbas intel_rapl iosf_mbi x86_pkg_temp_thermal snd_hda_codec_realtek i915_bpo intel_powerclamp coretemp wl(POE) snd_hda_codec_...

The problem just happened to me again. I am including bellow the dmesg information.

[39935.550219] ACPI: Waking up from system sleep state S3
[39936.749233] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
[39936.749486] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
[39936.749535] PM: noirq resume of devices complete after 16.818 msecs
[39936.772659] PM: early resume of devices complete after 23.055 msecs
[39936.772771] usb usb1: root hub lost power or was reset
[39936.772772] usb usb2: root hub lost power or was reset
[39936.773878] sd 3:0:0:0: [sda] Starting disk
[39936.774743] rtc_cmos 00:01: System wakeup disabled by ACPI
[39936.957171] cfg80211: Calling CRDA to update world regulatory domain
[39937.000773] usb 3-1: reset high-speed USB device number 2 using ehci-pci
[39937.096852] ata4: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[39937.098948] ata4.00: failed to get NCQ Send/Recv Log Emask 0x1
[39937.099202] ata4.00: failed to get NCQ Send/Recv Log Emask 0x1
[39937.099240] ata4.00: configured for UDMA/133
[39937.277116] usb 1-3: reset full-speed USB device number 2 using xhci_hcd
[39937.406403] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e00
[39937.406404] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e90
[39937.406405] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e48
[39937.406412] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979948
[39937.406413] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979900
[39937.406413] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979288
[39937.406414] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979240
[39937.517280] usb 1-4: reset full-speed USB device number 3 using xhci_hcd
[39937.645962] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d90cd3c0
[39937.645963] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d90cd408
[39937.645976] usb 1-4: ep 0x2 - rounding interval to 64 microframes, ep desc says 80 microframes
[39937.757453] usb 1-5: reset high-speed USB device number 4 using xhci_hcd
[39937.995391] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d8fb2f60
[39937.995398] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d8fb2f00
[39938.038325] PM: resume of devices complete after 1264.639 msecs
[39938.038849] ------------[ cut here ]------------
[39938.038853] WARNING: CPU: 1 PID: 8500 at /build/buildd/linux-3.19.0/drivers/base/firmware_class.c:1126 _request_firmware+0x72e/0xbd0()
[39938.038877] Modules linked in: cpuid mmc_block ses enclosure uas usb_storage hid_generic hidp binfmt_misc rfcomm bnep nls_iso8859_1 uvcvideo videobuf2_vmalloc videobuf2_memops btusb videobuf2_core bluetooth v4l2_common videodev usbhid media dell_led joydev dell_wmi hid_multitouch sparse_keymap dell_laptop dcdbas intel_rapl iosf_mbi x86_pkg_temp_thermal snd_hda_codec_realtek i915_bpo intel_powerclamp coretemp wl(POE) snd_hda_codec_generic intel_ips kvm_intel kvm crct10dif_pclmul crc32_pclmul ghash_clmulni_intel aesni_intel aes_x86_64 lrw gf128mul glue_helper ablk_helper cryptd cfg80211 snd_hda_intel rtsx_pci_ms snd_hda_controller serio_raw snd_hda_codec memstick lpc_ich snd_hwdep snd_seq_midi snd_soc_rt286 snd_seq_midi_event snd_soc_core snd_rawmidi snd_compress snd_pcm_dmaengine i915 snd_pcm snd_seq
[39938.038890]  i2c_hid snd_seq_device hid drm_kms_helper drm snd_timer mei_me snd mei shpchp soundcore i2c_algo_bit wmi soc_button_array snd_soc_sst_acpi i2c_designware_platform int3403_thermal i2c_designware_core dw_dmac video 8250_dw dw_dmac_core int3400_thermal int3402_thermal acpi_thermal_rel spi_pxa2xx_platform processor_thermal_device acpi_pad mac_hid parport_pc ppdev lp parport autofs4 rtsx_pci_sdmmc ahci psmouse libahci rtsx_pci sdhci_acpi sdhci
[39938.038892] CPU: 1 PID: 8500 Comm: kworker/u9:0 Tainted: P        W  OE  3.19.0-18-generic #18-Ubuntu
[39938.038892] Hardware name: Dell Inc. XPS 13 9343/0310JH, BIOS A03 03/25/2015
[39938.038900] Workqueue: hci0 hci_power_on [bluetooth]
[39938.038902]  ffffffff81b10ca0 ffff8800da35bb78 ffffffff817c27cd ffffffff81c54db8
[39938.038903]  0000000000000000 ffff8800da35bbb8 ffffffff8107593a ffffffff815196ca
[39938.038904]  ffff8800da35bcd0 ffff8800bbc185a0 ffff8801c299b780 00000000fffffff5
[39938.038904] Call Trace:
[39938.038907]  [<ffffffff817c27cd>] dump_stack+0x45/0x57
[39938.038909]  [<ffffffff8107593a>] warn_slowpath_common+0x8a/0xc0
[39938.038910]  [<ffffffff815196ca>] ? _request_firmware+0x5a/0xbd0
[39938.038911]  [<ffffffff81075a2a>] warn_slowpath_null+0x1a/0x20
[39938.038912]  [<ffffffff81519d9e>] _request_firmware+0x72e/0xbd0
[39938.038913]  [<ffffffff8151a275>] request_firmware+0x35/0x50
[39938.038916]  [<ffffffffc0673869>] btusb_setup_bcm_patchram+0x89/0x570 [btusb]
[39938.038917]  [<ffffffff81510f46>] ? rpm_idle+0xd6/0x230
[39938.038922]  [<ffffffffc0e6a561>] hci_dev_do_open+0xe1/0xa90 [bluetooth]
[39938.038927]  [<ffffffffc0e6b6f0>] hci_power_on+0x40/0x200 [bluetooth]
[39938.038929]  [<ffffffff8108eb58>] process_one_work+0x158/0x430
[39938.038930]  [<ffffffff8108f69b>] worker_thread+0x5b/0x530
[39938.038931]  [<ffffffff8108f640>] ? rescuer_thread+0x3a0/0x3a0
[39938.038932]  [<ffffffff81094739>] kthread+0xc9/0xe0
[39938.038934]  [<ffffffff81094670>] ? kthread_create_on_node+0x1c0/0x1c0
[39938.038936]  [<ffffffff817c9858>] ret_from_fork+0x58/0x90
[39938.038937]  [<ffffffff81094670>] ? kthread_create_on_node+0x1c0/0x1c0
[39938.038938] ---[ end trace 65c9a7c064322ff2 ]---
[39938.038940] bluetooth hci0: firmware: brcm/BCM20702A0-0a5c-216f.hcd will not be loaded
[39938.038941] Bluetooth: hci0: BCM: patch brcm/BCM20702A0-0a5c-216f.hcd not found
[39938.039014] PM: Finishing wakeup.
[39938.039015] Restarting tasks ... 
[39938.039569] pci_bus 0000:01: Allocating resources
[39938.039682] pci_bus 0000:02: Allocating resources
[39938.039725] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[39938.039913] pci_bus 0000:01: Allocating resources
[39938.040023] pci_bus 0000:02: Allocating resources
[39938.040065] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[39938.040139] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[39938.040244] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[39938.040341] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[39938.040671] pci_bus 0000:01: Allocating resources
[39938.040791] pci_bus 0000:02: Allocating resources
[39938.040833] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[39938.041469] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[39938.041548] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[39938.041650] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[39938.041669] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[39938.041688] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[39938.045090] done.
[39938.046734] cfg80211: World regulatory domain updated:
[39938.046737] cfg80211:  DFS Master region: unset
[39938.046739] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[39938.046742] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[39938.046744] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[39938.046746] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm), (N/A)
[39938.046748] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[39938.046750] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[39938.109603] systemd[1]: Unit suspend.target is bound to inactive unit systemd-suspend.service. Stopping, too.
[39953.501153] hid-generic 0005:046D:B00E.0006: unknown main item tag 0x0
[39953.763130] input: Dell Travel Mouse WM524 as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.0/bluetooth/hci0/hci0:12/0005:046D:B00E.0006/input/input24
[39953.763453] hid-generic 0005:046D:B00E.0006: input,hidraw2: BLUETOOTH HID v8.00 Mouse [Dell Travel Mouse WM524] on ac:d1:b8:c0:6f:5c
cribari@darwin4:~$

Revision history for this message

Francisco Cribari (cribari) wrote on 2015-05-22:

#10

I then did

sudo modprobe -r btusb
sudo modprobe btusb

and the BT mouse was no longer laggy and erratic.

Revision history for this message

Francisco Cribari (cribari) wrote on 2015-05-23:

#11

Download full text (24.9 KiB)

The problem just happened again. I am enclosing below information obtained from dmesg.

[54069.332038] CPU3 is up
[54069.335595] ACPI: Waking up from system sleep state S3
[54070.521751] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
[54070.522779] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
[54070.522842] PM: noirq resume of devices complete after 17.080 msecs
[54070.545697] PM: early resume of devices complete after 22.808 msecs
[54070.545796] usb usb1: root hub lost power or was reset
[54070.545798] usb usb2: root hub lost power or was reset
[54070.547460] rtc_cmos 00:01: System wakeup disabled by ACPI
[54070.561611] sd 3:0:0:0: [sda] Starting disk
[54070.730554] cfg80211: Calling CRDA to update world regulatory domain
[54070.781847] usb 3-1: reset high-speed USB device number 2 using ehci-pci
[54070.881907] ata4: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[54070.884034] ata4.00: failed to get NCQ Send/Recv Log Emask 0x1
[54070.884415] ata4.00: failed to get NCQ Send/Recv Log Emask 0x1
[54070.884447] ata4.00: configured for UDMA/133
[54071.050074] usb 1-5: reset high-speed USB device number 4 using xhci_hcd
[54071.288028] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d8fb2f60
[54071.288029] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d8fb2f00
[54071.402440] usb 1-3: reset full-speed USB device number 2 using xhci_hcd
[54071.531804] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e00
[54071.531806] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e90
[54071.531806] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e48
[54071.531807] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979948
[54071.531808] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979900
[54071.531808] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979288
[54071.531809] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979240
[54071.642640] usb 1-4: reset full-speed USB device number 3 using xhci_hcd
[54071.771282] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d90cd3c0
[54071.771283] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d90cd408
[54071.771291] usb 1-4: ep 0x2 - rounding interval to 64 microframes, ep desc says 80 microframes
[54071.776773] PM: resume of devices complete after 1230.077 msecs
[54071.777403] PM: Finishing wakeup.
[54071.777404] Restarting tasks ...
[54071.777731] pci_bus 0000:01: Allocating resources
[54071.777846] pci_bus 0000:02: Allocating resources
[54071.777890] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.779421] pci_bus 0000:01: Allocating resources
[54071.779540] pci_bus 0000:02: Allocating resources
[54071.779584] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.779658] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.779754] i915 0000:00:02.0: BAR 6: [??? 0x...

The problem just happened again. I am enclosing below information obtained from dmesg.

[54069.332038] CPU3 is up
[54069.335595] ACPI: Waking up from system sleep state S3
[54070.521751] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
[54070.522779] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
[54070.522842] PM: noirq resume of devices complete after 17.080 msecs
[54070.545697] PM: early resume of devices complete after 22.808 msecs
[54070.545796] usb usb1: root hub lost power or was reset
[54070.545798] usb usb2: root hub lost power or was reset
[54070.547460] rtc_cmos 00:01: System wakeup disabled by ACPI
[54070.561611] sd 3:0:0:0: [sda] Starting disk
[54070.730554] cfg80211: Calling CRDA to update world regulatory domain
[54070.781847] usb 3-1: reset high-speed USB device number 2 using ehci-pci
[54070.881907] ata4: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[54070.884034] ata4.00: failed to get NCQ Send/Recv Log Emask 0x1
[54070.884415] ata4.00: failed to get NCQ Send/Recv Log Emask 0x1
[54070.884447] ata4.00: configured for UDMA/133
[54071.050074] usb 1-5: reset high-speed USB device number 4 using xhci_hcd
[54071.288028] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d8fb2f60
[54071.288029] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d8fb2f00
[54071.402440] usb 1-3: reset full-speed USB device number 2 using xhci_hcd
[54071.531804] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e00
[54071.531806] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e90
[54071.531806] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e48
[54071.531807] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979948
[54071.531808] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979900
[54071.531808] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979288
[54071.531809] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979240
[54071.642640] usb 1-4: reset full-speed USB device number 3 using xhci_hcd
[54071.771282] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d90cd3c0
[54071.771283] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d90cd408
[54071.771291] usb 1-4: ep 0x2 - rounding interval to 64 microframes, ep desc says 80 microframes
[54071.776773] PM: resume of devices complete after 1230.077 msecs
[54071.777403] PM: Finishing wakeup.
[54071.777404] Restarting tasks ... 
[54071.777731] pci_bus 0000:01: Allocating resources
[54071.777846] pci_bus 0000:02: Allocating resources
[54071.777890] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.779421] pci_bus 0000:01: Allocating resources
[54071.779540] pci_bus 0000:02: Allocating resources
[54071.779584] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.779658] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.779754] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.779835] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.780169] pci_bus 0000:01: Allocating resources
[54071.780187] pci_bus 0000:02: Allocating resources
[54071.780228] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.780893] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.780960] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.781056] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.781071] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.781088] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[54071.781106] Bluetooth: hci0: BCM: patching hci_ver=06 hci_rev=1000 lmp_ver=06 lmp_subver=220e
[54071.782857] done.
[54071.785656] cfg80211: World regulatory domain updated:
[54071.785660] cfg80211:  DFS Master region: unset
[54071.785661] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[54071.785664] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[54071.785666] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[54071.785668] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm), (N/A)
[54071.785670] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[54071.785671] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[54072.397622] Bluetooth: hci0: BCM: firmware hci_ver=06 hci_rev=1624 lmp_ver=06 lmp_subver=220e
[54086.545625] hid-generic 0005:046D:B00E.0009: unknown main item tag 0x0
[54086.584968] input: Dell Travel Mouse WM524 as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.0/bluetooth/hci0/hci0:11/0005:046D:B00E.0009/input/input29
[54086.585178] hid-generic 0005:046D:B00E.0009: input,hidraw2: BLUETOOTH HID v8.00 Mouse [Dell Travel Mouse WM524] on ac:d1:b8:c0:6f:5c
[55401.421090] ------------[ cut here ]------------
[55401.421112] WARNING: CPU: 1 PID: 366 at /build/buildd/linux-3.19.0/net/wireless/sme.c:800 cfg80211_roamed+0x91/0xa0 [cfg80211]()
[55401.421113] Modules linked in: btusb cpuid mmc_block ses enclosure uas usb_storage hid_generic hidp binfmt_misc rfcomm bnep nls_iso8859_1 uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core bluetooth v4l2_common videodev usbhid media dell_led joydev dell_wmi hid_multitouch sparse_keymap dell_laptop dcdbas intel_rapl iosf_mbi x86_pkg_temp_thermal snd_hda_codec_realtek i915_bpo intel_powerclamp coretemp wl(POE) snd_hda_codec_generic intel_ips kvm_intel kvm crct10dif_pclmul crc32_pclmul ghash_clmulni_intel aesni_intel aes_x86_64 lrw gf128mul glue_helper ablk_helper cryptd cfg80211 snd_hda_intel rtsx_pci_ms snd_hda_controller serio_raw snd_hda_codec memstick lpc_ich snd_hwdep snd_seq_midi snd_soc_rt286 snd_seq_midi_event snd_soc_core snd_rawmidi snd_compress snd_pcm_dmaengine i915 snd_pcm snd_seq
[55401.421136]  i2c_hid snd_seq_device hid drm_kms_helper drm snd_timer mei_me snd mei shpchp soundcore i2c_algo_bit wmi soc_button_array snd_soc_sst_acpi i2c_designware_platform int3403_thermal i2c_designware_core dw_dmac video 8250_dw dw_dmac_core int3400_thermal int3402_thermal acpi_thermal_rel spi_pxa2xx_platform processor_thermal_device acpi_pad mac_hid parport_pc ppdev lp parport autofs4 rtsx_pci_sdmmc ahci psmouse libahci rtsx_pci sdhci_acpi sdhci [last unloaded: btusb]
[55401.421157] CPU: 1 PID: 366 Comm: wl_event_handle Tainted: P        W  OE  3.19.0-18-generic #18-Ubuntu
[55401.421158] Hardware name: Dell Inc. XPS 13 9343/0310JH, BIOS A03 03/25/2015
[55401.421159]  ffffffffc0528a78 ffff8800d9ffbda8 ffffffff817c27cd 0000000000000007
[55401.421160]  0000000000000000 ffff8800d9ffbde8 ffffffff8107593a ffff8800da2a0c78
[55401.421162]  ffff880214639000 ffff88017adac9c0 0000000000000098 ffff88017adacd80
[55401.421164] Call Trace:
[55401.421169]  [<ffffffff817c27cd>] dump_stack+0x45/0x57
[55401.421172]  [<ffffffff8107593a>] warn_slowpath_common+0x8a/0xc0
[55401.421173]  [<ffffffff81075a2a>] warn_slowpath_null+0x1a/0x20
[55401.421181]  [<ffffffffc0504aa1>] cfg80211_roamed+0x91/0xa0 [cfg80211]
[55401.421209]  [<ffffffffc09bf9fb>] wl_notify_roaming_status+0xcb/0x150 [wl]
[55401.421232]  [<ffffffffc09bc75a>] wl_event_handler+0x6a/0x230 [wl]
[55401.421255]  [<ffffffffc09bc6f0>] ? wl_free_wdev.isra.23+0x80/0x80 [wl]
[55401.421258]  [<ffffffff81094739>] kthread+0xc9/0xe0
[55401.421260]  [<ffffffff81094670>] ? kthread_create_on_node+0x1c0/0x1c0
[55401.421263]  [<ffffffff817c9858>] ret_from_fork+0x58/0x90
[55401.421265]  [<ffffffff81094670>] ? kthread_create_on_node+0x1c0/0x1c0
[55401.421267] ---[ end trace 65c9a7c064322ff3 ]---
[57279.096393] usb 2-2: new SuperSpeed USB device number 5 using xhci_hcd
[57279.113028] usb 2-2: New USB device found, idVendor=1058, idProduct=0820
[57279.113032] usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=5
[57279.113035] usb 2-2: Product: My Passport 0820
[57279.113037] usb 2-2: Manufacturer: Western Digital
[57279.113038] usb 2-2: SerialNumber: 575836314142334E30303337
[57279.113655] usb-storage 2-2:1.0: USB Mass Storage device detected
[57279.113850] scsi host7: usb-storage 2-2:1.0
[57280.113371] scsi 7:0:0:0: Direct-Access     WD       My Passport 0820 1007 PQ: 0 ANSI: 6
[57280.113526] scsi 7:0:0:1: Enclosure         WD       SES Device       1007 PQ: 0 ANSI: 6
[57280.114364] sd 7:0:0:0: Attached scsi generic sg1 type 0
[57280.114471] ses 7:0:0:1: Attached Enclosure device
[57280.114540] ses 7:0:0:1: Attached scsi generic sg2 type 13
[57286.175048] sd 7:0:0:0: [sdb] 3906963456 512-byte logical blocks: (2.00 TB/1.81 TiB)
[57286.175450] sd 7:0:0:0: [sdb] Write Protect is off
[57286.175452] sd 7:0:0:0: [sdb] Mode Sense: 47 00 10 08
[57286.175777] sd 7:0:0:0: [sdb] No Caching mode page found
[57286.175779] sd 7:0:0:0: [sdb] Assuming drive cache: write through
[57286.191620]  sdb: sdb1
[57286.192832] sd 7:0:0:0: [sdb] Attached SCSI disk
[57286.492531] EXT4-fs (sdb1): warning: mounting fs with errors, running e2fsck is recommended
[57286.521799] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null)
[57586.933482] EXT4-fs (sdb1): error count since last fsck: 209
[57586.933485] EXT4-fs (sdb1): initial error at time 1398807691: ext4_mb_generate_buddy:756
[57586.933487] EXT4-fs (sdb1): last error at time 1432231433: ext4_mb_generate_buddy:757
[62085.839945] cfg80211: Calling CRDA to update world regulatory domain
[62085.873568] cfg80211: World regulatory domain updated:
[62085.873571] cfg80211:  DFS Master region: unset
[62085.873573] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[62085.873575] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[62085.873577] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[62085.873579] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm), (N/A)
[62085.873580] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[62085.873582] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[62185.785646] cfg80211: Calling CRDA to update world regulatory domain
[62185.788212] cfg80211: World regulatory domain updated:
[62185.788215] cfg80211:  DFS Master region: unset
[62185.788217] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[62185.788219] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[62185.788221] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[62185.788223] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm), (N/A)
[62185.788224] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[62185.788226] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[66297.170508] ------------[ cut here ]------------
[66297.170529] WARNING: CPU: 2 PID: 366 at /build/buildd/linux-3.19.0/net/wireless/sme.c:800 cfg80211_roamed+0x91/0xa0 [cfg80211]()
[66297.170530] Modules linked in: btusb cpuid mmc_block ses enclosure uas usb_storage hid_generic hidp binfmt_misc rfcomm bnep nls_iso8859_1 uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core bluetooth v4l2_common videodev usbhid media dell_led joydev dell_wmi hid_multitouch sparse_keymap dell_laptop dcdbas intel_rapl iosf_mbi x86_pkg_temp_thermal snd_hda_codec_realtek i915_bpo intel_powerclamp coretemp wl(POE) snd_hda_codec_generic intel_ips kvm_intel kvm crct10dif_pclmul crc32_pclmul ghash_clmulni_intel aesni_intel aes_x86_64 lrw gf128mul glue_helper ablk_helper cryptd cfg80211 snd_hda_intel rtsx_pci_ms snd_hda_controller serio_raw snd_hda_codec memstick lpc_ich snd_hwdep snd_seq_midi snd_soc_rt286 snd_seq_midi_event snd_soc_core snd_rawmidi snd_compress snd_pcm_dmaengine i915 snd_pcm snd_seq
[66297.170552]  i2c_hid snd_seq_device hid drm_kms_helper drm snd_timer mei_me snd mei shpchp soundcore i2c_algo_bit wmi soc_button_array snd_soc_sst_acpi i2c_designware_platform int3403_thermal i2c_designware_core dw_dmac video 8250_dw dw_dmac_core int3400_thermal int3402_thermal acpi_thermal_rel spi_pxa2xx_platform processor_thermal_device acpi_pad mac_hid parport_pc ppdev lp parport autofs4 rtsx_pci_sdmmc ahci psmouse libahci rtsx_pci sdhci_acpi sdhci [last unloaded: btusb]
[66297.170571] CPU: 2 PID: 366 Comm: wl_event_handle Tainted: P        W  OE  3.19.0-18-generic #18-Ubuntu
[66297.170572] Hardware name: Dell Inc. XPS 13 9343/0310JH, BIOS A03 03/25/2015
[66297.170573]  ffffffffc0528a78 ffff8800d9ffbda8 ffffffff817c27cd 0000000000000007
[66297.170574]  0000000000000000 ffff8800d9ffbde8 ffffffff8107593a ffff8800da2a0c78
[66297.170576]  ffff880214639000 ffff8801fd72ba80 0000000000000098 ffff8801fd72bcc0
[66297.170578] Call Trace:
[66297.170582]  [<ffffffff817c27cd>] dump_stack+0x45/0x57
[66297.170585]  [<ffffffff8107593a>] warn_slowpath_common+0x8a/0xc0
[66297.170586]  [<ffffffff81075a2a>] warn_slowpath_null+0x1a/0x20
[66297.170593]  [<ffffffffc0504aa1>] cfg80211_roamed+0x91/0xa0 [cfg80211]
[66297.170619]  [<ffffffffc09bf9fb>] wl_notify_roaming_status+0xcb/0x150 [wl]
[66297.170640]  [<ffffffffc09bc75a>] wl_event_handler+0x6a/0x230 [wl]
[66297.170660]  [<ffffffffc09bc6f0>] ? wl_free_wdev.isra.23+0x80/0x80 [wl]
[66297.170662]  [<ffffffff81094739>] kthread+0xc9/0xe0
[66297.170665]  [<ffffffff81094670>] ? kthread_create_on_node+0x1c0/0x1c0
[66297.170667]  [<ffffffff817c9858>] ret_from_fork+0x58/0x90
[66297.170669]  [<ffffffff81094670>] ? kthread_create_on_node+0x1c0/0x1c0
[66297.170670] ---[ end trace 65c9a7c064322ff4 ]---
[71669.005535] usb 2-2: USB disconnect, device number 5
[71685.823238] cfg80211: Calling CRDA to update world regulatory domain
[71685.829498] cfg80211: World regulatory domain updated:
[71685.829502] cfg80211:  DFS Master region: unset
[71685.829503] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[71685.829506] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[71685.829508] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[71685.829510] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm), (N/A)
[71685.829512] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[71685.829514] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[71685.831602] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[71686.257904] PM: Syncing filesystems ... done.
[71686.274884] PM: Preparing system for mem sleep
[71686.275051] Freezing user space processes ... (elapsed 0.002 seconds) done.
[71686.278046] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[71686.279261] PM: Entering mem sleep
[71686.279282] Suspending console(s) (use no_console_suspend to debug)
[71686.280730] sd 3:0:0:0: [sda] Synchronizing SCSI cache
[71686.282784] sd 3:0:0:0: [sda] Stopping disk
[71687.300421] [drm:stop_ring [i915]] *ERROR* render ring : timed out trying to stop ring
[71687.308453] PM: suspend of devices complete after 1028.097 msecs
[71687.348442] PM: late suspend of devices complete after 39.952 msecs
[71687.348916] ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
[71687.349021] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
[71687.364601] PM: noirq suspend of devices complete after 16.143 msecs
[71687.364875] ACPI: Preparing to enter system sleep state S3
[71687.384490] PM: Saving platform NVS memory
[71687.384507] Disabling non-boot CPUs ...
[71687.384535] intel_pstate CPU 1 exiting
[71687.385670] kvm: disabling virtualization on CPU1
[71687.385677] smpboot: CPU 1 is now offline
[71687.385983] intel_pstate CPU 2 exiting
[71687.387119] kvm: disabling virtualization on CPU2
[71687.387126] smpboot: CPU 2 is now offline
[71687.387405] intel_pstate CPU 3 exiting
[71687.387493] Broke affinity for irq 21
[71687.387498] Broke affinity for irq 43
[71687.387500] Broke affinity for irq 44
[71687.387502] Broke affinity for irq 46
[71687.388507] kvm: disabling virtualization on CPU3
[71687.388518] smpboot: CPU 3 is now offline
[71687.390177] ACPI: Low-level resume complete
[71687.390228] PM: Restoring platform NVS memory
[71687.390641] Enabling non-boot CPUs ...
[71687.390676] x86: Booting SMP configuration:
[71687.390676] smpboot: Booting Node 0 Processor 1 APIC 0x2
[71687.402691] kvm: enabling virtualization on CPU1
[71687.404922] CPU1 is up
[71687.404940] smpboot: Booting Node 0 Processor 2 APIC 0x1
[71687.416935] kvm: enabling virtualization on CPU2
[71687.419136] CPU2 is up
[71687.419149] smpboot: Booting Node 0 Processor 3 APIC 0x3
[71687.431141] kvm: enabling virtualization on CPU3
[71687.433338] CPU3 is up
[71687.436915] ACPI: Waking up from system sleep state S3
[71688.623573] ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
[71688.623679] xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
[71688.623733] PM: noirq resume of devices complete after 16.431 msecs
[71688.647333] PM: early resume of devices complete after 23.528 msecs
[71688.647488] usb usb1: root hub lost power or was reset
[71688.647491] usb usb2: root hub lost power or was reset
[71688.649169] rtc_cmos 00:01: System wakeup disabled by ACPI
[71688.659974] sd 3:0:0:0: [sda] Starting disk
[71688.832725] cfg80211: Calling CRDA to update world regulatory domain
[71688.875387] usb 3-1: reset high-speed USB device number 2 using ehci-pci
[71688.967367] ata4: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[71688.969288] ata4.00: failed to get NCQ Send/Recv Log Emask 0x1
[71688.969482] ata4.00: failed to get NCQ Send/Recv Log Emask 0x1
[71688.969514] ata4.00: configured for UDMA/133
[71689.151686] usb 1-3: reset full-speed USB device number 2 using xhci_hcd
[71689.281035] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e00
[71689.281036] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e90
[71689.281037] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802149b9e48
[71689.281038] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979948
[71689.281038] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979900
[71689.281039] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979288
[71689.281040] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff880212979240
[71689.391896] usb 1-4: reset full-speed USB device number 3 using xhci_hcd
[71689.520534] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d90cd3c0
[71689.520535] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d90cd408
[71689.520542] usb 1-4: ep 0x2 - rounding interval to 64 microframes, ep desc says 80 microframes
[71689.632075] usb 1-5: reset high-speed USB device number 4 using xhci_hcd
[71689.869948] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d8fb2f60
[71689.869949] xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800d8fb2f00
[71689.912810] PM: resume of devices complete after 1264.439 msecs
[71689.913180] ------------[ cut here ]------------
[71689.913183] WARNING: CPU: 2 PID: 11880 at /build/buildd/linux-3.19.0/drivers/base/firmware_class.c:1126 _request_firmware+0x72e/0xbd0()
[71689.913208] Modules linked in: btusb cpuid mmc_block ses enclosure uas usb_storage hid_generic hidp binfmt_misc rfcomm bnep nls_iso8859_1 uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_core bluetooth v4l2_common videodev usbhid media dell_led joydev dell_wmi hid_multitouch sparse_keymap dell_laptop dcdbas intel_rapl iosf_mbi x86_pkg_temp_thermal snd_hda_codec_realtek i915_bpo intel_powerclamp coretemp wl(POE) snd_hda_codec_generic intel_ips kvm_intel kvm crct10dif_pclmul crc32_pclmul ghash_clmulni_intel aesni_intel aes_x86_64 lrw gf128mul glue_helper ablk_helper cryptd cfg80211 snd_hda_intel rtsx_pci_ms snd_hda_controller serio_raw snd_hda_codec memstick lpc_ich snd_hwdep snd_seq_midi snd_soc_rt286 snd_seq_midi_event snd_soc_core snd_rawmidi snd_compress snd_pcm_dmaengine i915 snd_pcm snd_seq
[71689.913221]  i2c_hid snd_seq_device hid drm_kms_helper drm snd_timer mei_me snd mei shpchp soundcore i2c_algo_bit wmi soc_button_array snd_soc_sst_acpi i2c_designware_platform int3403_thermal i2c_designware_core dw_dmac video 8250_dw dw_dmac_core int3400_thermal int3402_thermal acpi_thermal_rel spi_pxa2xx_platform processor_thermal_device acpi_pad mac_hid parport_pc ppdev lp parport autofs4 rtsx_pci_sdmmc ahci psmouse libahci rtsx_pci sdhci_acpi sdhci [last unloaded: btusb]
[71689.913223] CPU: 2 PID: 11880 Comm: kworker/u9:0 Tainted: P        W  OE  3.19.0-18-generic #18-Ubuntu
[71689.913224] Hardware name: Dell Inc. XPS 13 9343/0310JH, BIOS A03 03/25/2015
[71689.913232] Workqueue: hci0 hci_power_on [bluetooth]
[71689.913233]  ffffffff81b10ca0 ffff8801240cfb78 ffffffff817c27cd ffffffff81c54db8
[71689.913234]  0000000000000000 ffff8801240cfbb8 ffffffff8107593a ffffffff815196ca
[71689.913235]  ffff8801240cfcd0 ffff88009e34ac40 ffff8800c4b34480 00000000fffffff5
[71689.913235] Call Trace:
[71689.913239]  [<ffffffff817c27cd>] dump_stack+0x45/0x57
[71689.913241]  [<ffffffff8107593a>] warn_slowpath_common+0x8a/0xc0
[71689.913243]  [<ffffffff815196ca>] ? _request_firmware+0x5a/0xbd0
[71689.913244]  [<ffffffff81075a2a>] warn_slowpath_null+0x1a/0x20
[71689.913245]  [<ffffffff81519d9e>] _request_firmware+0x72e/0xbd0
[71689.913246]  [<ffffffff8151a275>] request_firmware+0x35/0x50
[71689.913247]  [<ffffffffc07f1869>] btusb_setup_bcm_patchram+0x89/0x570 [btusb]
[71689.913249]  [<ffffffff81510f46>] ? rpm_idle+0xd6/0x230
[71689.913254]  [<ffffffffc0e6a561>] hci_dev_do_open+0xe1/0xa90 [bluetooth]
[71689.913259]  [<ffffffffc0e6b6f0>] hci_power_on+0x40/0x200 [bluetooth]
[71689.913263]  [<ffffffff8108eb58>] process_one_work+0x158/0x430
[71689.913264]  [<ffffffff8108f69b>] worker_thread+0x5b/0x530
[71689.913266]  [<ffffffff8108f640>] ? rescuer_thread+0x3a0/0x3a0
[71689.913269]  [<ffffffff81094739>] kthread+0xc9/0xe0
[71689.913271]  [<ffffffff81094670>] ? kthread_create_on_node+0x1c0/0x1c0
[71689.913274]  [<ffffffff817c9858>] ret_from_fork+0x58/0x90
[71689.913276]  [<ffffffff81094670>] ? kthread_create_on_node+0x1c0/0x1c0
[71689.913277] ---[ end trace 65c9a7c064322ff5 ]---
[71689.913279] bluetooth hci0: firmware: brcm/BCM20702A0-0a5c-216f.hcd will not be loaded
[71689.913281] Bluetooth: hci0: BCM: patch brcm/BCM20702A0-0a5c-216f.hcd not found
[71689.913431] PM: Finishing wakeup.
[71689.913432] Restarting tasks ... 
[71689.913842] pci_bus 0000:01: Allocating resources
[71689.913960] pci_bus 0000:02: Allocating resources
[71689.914005] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[71689.914240] pci_bus 0000:01: Allocating resources
[71689.914359] pci_bus 0000:02: Allocating resources
[71689.914405] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[71689.914536] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[71689.914659] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[71689.914782] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[71689.915157] pci_bus 0000:01: Allocating resources
[71689.915281] pci_bus 0000:02: Allocating resources
[71689.915326] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[71689.916397] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[71689.916502] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[71689.916639] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[71689.916669] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[71689.916693] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[71689.923762] cfg80211: World regulatory domain updated:
[71689.923765] cfg80211:  DFS Master region: unset
[71689.923766] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[71689.923768] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[71689.923769] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[71689.923770] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm), (N/A)
[71689.923771] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[71689.923772] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm), (N/A)
[71689.924176] done.
[71690.224385] systemd[1]: Unit suspend.target is bound to inactive unit systemd-suspend.service. Stopping, too.
[71710.852638] hid-generic 0005:046D:B00E.000A: unknown main item tag 0x0
[71711.232246] input: Dell Travel Mouse WM524 as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.0/bluetooth/hci0/hci0:12/0005:046D:B00E.000A/input/input32
[71711.232553] hid-generic 0005:046D:B00E.000A: input,hidraw2: BLUETOOTH HID v8.00 Mouse [Dell Travel Mouse WM524] on ac:d1:b8:c0:6f:5c

Keng-Yu Lin (lexical) on 2015-06-16

no longer affects:

hwe-next

Revision history for this message

Launchpad Janitor (janitor) wrote on 2015-08-18:

#12

Status changed to 'Confirmed' because the bug affects multiple users.

Changed in bluez (Ubuntu):
status:	New → Confirmed
Changed in linux-firmware (Ubuntu):
status:	New → Confirmed

Alberto Salvia Novella (es20490446e) on 2015-08-21

Changed in bluez (Ubuntu):
importance:	Undecided → Medium
Changed in linux-firmware (Ubuntu):
importance:	Undecided → Medium

Revision history for this message

Alejandro R. Mosteo (mosteo) wrote on 2015-12-29:

#14

I observe this in a 15.10 installation.

Linux 4.2.0-18-generic #22-Ubuntu SMP Fri Nov 6 23:32:28 UTC 2015 i686 i686 i686 GNU/Linux

I'm using a logitech bluetooth kb+mouse. Everything works normally, then I watch a movie in Kodi and when I exit to desktop again, mouse is very laggy. So some kind of usb suspend might be involved.

Within Kodi, after a while, the keyboard won't even reconnect so I have to exit with a remote. Once in the desktop the slugginesh manifests itself.

Reloading the module as in comment #10 works for me, thanks for the workaround.

Revision history for this message

Allan (allan-brighton) wrote on 2016-03-10:

#15

I am seeing this with a Logitech Bluetooth Laser travel Mouse on Ubuntu-16.04.
(The system is set to not sleep or suspend.)

The fix mentioned above seems to work:

sudo modprobe -r btusb
sudo modprobe btusb

Here is some output from dmesg:

[ 11.035464] vboxdrv: Found 8 processor cores
[ 11.053922] vboxdrv: TSC mode is Invariant, tentative frequency 2712000012 Hz
[ 11.053924] vboxdrv: Successfully loaded version 5.0.16_Ubuntu (interface 0x00240000)
[ 11.058160] VBoxNetFlt: Successfully started.
[ 11.061432] VBoxNetAdp: Successfully started.
[ 11.064505] VBoxPciLinuxInit
[ 11.067342] vboxpci: IOMMU not found (not registered)
[ 12.027559] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[ 12.027562] Bluetooth: HIDP socket layer initialized
[ 12.028358] hid-generic 0005:046D:B008.0002: unknown main item tag 0x0
[ 12.028479] input: Bluetooth Laser Travel Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-14/1-14:1.0/bluetooth/hci0/hci0:256/0005:046D:B008.0002/input/input13
[ 12.029348] hid-generic 0005:046D:B008.0002: input,hidraw1: BLUETOOTH HID v3.14 Mouse [Bluetooth Laser Travel Mouse] on a4:34:d9:93:30:9f
[ 124.165037] hid-generic 0005:046D:B318.0003: unknown main item tag 0x0
[ 124.165164] input: Logitech K760 as /devices/pci0000:00/0000:00:14.0/usb1/1-14/1-14:1.0/bluetooth/hci0/hci0:512/0005:046D:B318.0003/input/input14
[ 124.165511] hid-generic 0005:046D:B318.0003: input,hidraw2: BLUETOOTH HID v1.1b Keyboard [Logitech K760] on a4:34:d9:93:30:9f
[ 149.711166] hid-generic 0005:046D:B318.0004: unknown main item tag 0x0
[ 149.711466] input: Logitech K760 as /devices/pci0000:00/0000:00:14.0/usb1/1-14/1-14:1.0/bluetooth/hci0/hci0:512/0005:046D:B318.0004/input/input15
[ 149.712161] hid-generic 0005:046D:B318.0004: input,hidraw2: BLUETOOTH HID v1.1b Keyboard [Logitech K760] on a4:34:d9:93:30:9f
[ 168.931084] logitech-hidpp-device 0005:046D:B00C.0005: unknown main item tag 0x0
[ 168.943063] logitech-hidpp-device 0005:046D:B00C.0005: HID++ 2.0 device connected.
[ 169.059414] input: Logitech Rechargeable Trackpad T651 as /devices/pci0000:00/0000:00:14.0/usb1/1-14/1-14:1.0/bluetooth/hci0/hci0:768/0005:046D:B00C.0005/input/input16
[ 169.059652] logitech-hidpp-device 0005:046D:B00C.0005: input,hidraw3: BLUETOOTH HID v5.00 Mouse [Logitech Rechargeable Trackpad T651] on a4:34:d9:93:30:9f

I am seeing this with a Logitech Bluetooth Laser travel Mouse on Ubuntu-16.04.
(The system is set to not sleep or suspend.)

The fix mentioned above seems to work:

sudo modprobe -r btusb
  sudo modprobe btusb

Here is some output from dmesg:

[   11.035464] vboxdrv: Found 8 processor cores
[   11.053922] vboxdrv: TSC mode is Invariant, tentative frequency 2712000012 Hz
[   11.053924] vboxdrv: Successfully loaded version 5.0.16_Ubuntu (interface 0x00240000)
[   11.058160] VBoxNetFlt: Successfully started.
[   11.061432] VBoxNetAdp: Successfully started.
[   11.064505] VBoxPciLinuxInit
[   11.067342] vboxpci: IOMMU not found (not registered)
[   12.027559] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[   12.027562] Bluetooth: HIDP socket layer initialized
[   12.028358] hid-generic 0005:046D:B008.0002: unknown main item tag 0x0
[   12.028479] input: Bluetooth Laser Travel Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-14/1-14:1.0/bluetooth/hci0/hci0:256/0005:046D:B008.0002/input/input13
[   12.029348] hid-generic 0005:046D:B008.0002: input,hidraw1: BLUETOOTH HID v3.14 Mouse [Bluetooth Laser Travel Mouse] on a4:34:d9:93:30:9f
[  124.165037] hid-generic 0005:046D:B318.0003: unknown main item tag 0x0
[  124.165164] input: Logitech K760 as /devices/pci0000:00/0000:00:14.0/usb1/1-14/1-14:1.0/bluetooth/hci0/hci0:512/0005:046D:B318.0003/input/input14
[  124.165511] hid-generic 0005:046D:B318.0003: input,hidraw2: BLUETOOTH HID v1.1b Keyboard [Logitech K760] on a4:34:d9:93:30:9f
[  149.711166] hid-generic 0005:046D:B318.0004: unknown main item tag 0x0
[  149.711466] input: Logitech K760 as /devices/pci0000:00/0000:00:14.0/usb1/1-14/1-14:1.0/bluetooth/hci0/hci0:512/0005:046D:B318.0004/input/input15
[  149.712161] hid-generic 0005:046D:B318.0004: input,hidraw2: BLUETOOTH HID v1.1b Keyboard [Logitech K760] on a4:34:d9:93:30:9f
[  168.931084] logitech-hidpp-device 0005:046D:B00C.0005: unknown main item tag 0x0
[  168.943063] logitech-hidpp-device 0005:046D:B00C.0005: HID++ 2.0 device connected.
[  169.059414] input: Logitech Rechargeable Trackpad T651 as /devices/pci0000:00/0000:00:14.0/usb1/1-14/1-14:1.0/bluetooth/hci0/hci0:768/0005:046D:B00C.0005/input/input16
[  169.059652] logitech-hidpp-device 0005:046D:B00C.0005: input,hidraw3: BLUETOOTH HID v5.00 Mouse [Logitech Rechargeable Trackpad T651] on a4:34:d9:93:30:9f

Revision history for this message

Leonid (pilleo19) wrote on 2016-06-28:

#16

I have Samsung bluetooth keyboard bkb10 and laptop with bcm 43142 bluetooth adapter. Keyboard is very laggy(unsusable), and also connection drops frequetly (i guess usually it is kernel bug causing drops, it drops every time after some intel i915 bug in kernel, but not always after that intel bug). So i tried to install older bluez from bluez.org, and it works great now! It is stable on 5.27 Bluez, but starting from 5.28 keyboard is unusable. Try it, maybe it will help you also.

Revision history for this message

Leonid (pilleo19) wrote on 2016-06-28:

#17

I have to add that keyboard is not laggy with old bluez, but it is still disconnecting frequently.

Revision history for this message

Leonid (pilleo19) wrote on 2016-06-28:

#18

Bluez 5.40 on Ubuntu and 5.38 on CM12.1 phone also laggy. But one detail could help devs to find the bug. While pairing keyboard with PC i see a number, that i have to type on the keyboard to connect it it. So typing that numbers is always very fast and stable, i did try it a lot of times. But once paired, it becomes laggy, buggy, unusable.

Revision history for this message

Konrad Zapałowicz (kzapalowicz) wrote on 2016-06-28:

#19

Hey, thanks for the updates and the investigation. However just to clarify, you are talking about this keyboard http://www.sonymobile.com/global-en/products/accessories/bluetooth-keyboard-bkb10/ right? Also, do I understand rightly that once it is paired the connection gets disconnected?

As an addition to that could you paste the syslog with Bluetooth debug options enabled [you can find instructions there https://wiki.ubuntu.com/DebuggingBluetooth]? This is going to be really useful for us when we start working on this issue.

Thanks

Revision history for this message

Leonid (pilleo19) wrote on 2016-06-28:

#20

Download full text (6.8 KiB)

Thank you for your work)
The keyboard is http://www.samsung.com/us/support/owners/product/BKB-10USWEGXAR
Once it is paired it just becomes unstable. Big latency, and also Bbbuuuuugs liiike thisss. That particular bug is making it unusable. Also i was checking it in kacpimon, if acpi sees several key presses also, and it does. While that laggs there are no any errors at all in syslog or dmesg, only after disconnect. And it is not disconnected right after pairing. It is very random, but sometimes before error about disconnect drm:intel_pipe_update_end [i915] bug appears, sometimes not. Also important to mention that with windows 10 it works perfectcly (fast, stable, no disconnects). Every Bluez install i was installing with bluetooth library, but now i will try to work with native 5.37 lib and old and stable bluez to find if it is bluez or lib bug. A bit later i will give you my logs with buggy and with stable bluez. syslog with disconnect after intel error:
Jun 28 14:07:03 leonid-Lenovo-G500 kernel: [ 2630.762724] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=154570 end=154571) time 116 us, min 763, max 767, scanline start 762, end 768
Jun 28 14:08:26 leonid-Lenovo-G500 dbus[676]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service'
Jun 28 14:08:26 leonid-Lenovo-G500 systemd[1]: Starting Hostname Service...
Jun 28 14:08:26 leonid-Lenovo-G500 dbus[676]: [system] Successfully activated service 'org.freedesktop.hostname1'
Jun 28 14:08:26 leonid-Lenovo-G500 systemd[1]: Started Hostname Service.
Jun 28 14:08:26 leonid-Lenovo-G500 org.gtk.vfs.Daemon[1159]: ** (process:30582): WARNING **: Couldn't create directory monitor on smb://x-gnome-default-workgroup/. Error: Operation not supported by backend
Jun 28 14:09:10 leonid-Lenovo-G500 gnome-session[1294]: Gtk-Message: GtkDialog mapped without a transient parent. This is discouraged.
Jun 28 14:09:15 leonid-Lenovo-G500 org.gnome.zeitgeist.SimpleIndexer[1159]: ** (zeitgeist-fts:2368): WARNING **: Unable to get info on application://nautilus-autostart.desktop
Jun 28 14:09:58 leonid-Lenovo-G500 URfkill[667]: device_changed_cb: ideapad_bluetooth
Jun 28 14:09:58 leonid-Lenovo-G500 systemd[1]: Starting Load/Save RF Kill Switch Status...
Jun 28 14:09:58 leonid-Lenovo-G500 systemd[1]: Started Load/Save RF Kill Switch Status.
Jun 28 14:09:58 leonid-Lenovo-G500 kernel: [ 2805.143113] usb 1-1.3: USB disconnect, device number 4
Jun 28 14:09:58 leonid-Lenovo-G500 acpid: input device has been disconnected, fd 17
Jun 28 14:09:58 leonid-Lenovo-G500 bluetoothd[880]: (bluetoothd:880): GLib-CRITICAL **: Source ID 144 was not found when attempting to remove it
Jun 28 14:09:58 leonid-Lenovo-G500 URfkill[667]: device_changed_cb: hci0
Jun 28 14:09:58 leonid-Lenovo-G500 bluetoothd[880]: Endpoint unregistered: sender=:1.61 path=/MediaEndpoint/A2DPSource
Jun 28 14:09:58 leonid-Lenovo-G500 systemd-rfkill[1271]: Failed to open device: No such device
Jun 28 14:09:58 leonid-Lenovo-G500 bluetoothd[880]: Endpoint unregistered: sender=:1.61 path=/MediaEndpoint/A2DPSink
Jun 28 14:09:58 leonid-Lenovo-G500 URfkill[667]: removing killswi...

Thank you for your work) 
The keyboard is http://www.samsung.com/us/support/owners/product/BKB-10USWEGXAR
Once it is paired it just becomes unstable. Big latency, and also Bbbuuuuugs liiike thisss. That particular bug is making it unusable. Also i was checking it in kacpimon, if acpi sees several key presses also, and it does. While that laggs there are no any errors at all in syslog or dmesg, only after disconnect. And it is not disconnected right after pairing. It is very random, but sometimes before error about disconnect drm:intel_pipe_update_end [i915] bug appears, sometimes not. Also important to mention that with windows 10 it works perfectcly (fast, stable, no disconnects). Every Bluez install i was installing with bluetooth library, but now i will try to work with native 5.37 lib and old and stable bluez to find if it is bluez or lib bug. A bit later i will give you my logs with buggy and with stable bluez. syslog with disconnect after intel error:
Jun 28 14:07:03 leonid-Lenovo-G500 kernel: [ 2630.762724] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=154570 end=154571) time 116 us, min 763, max 767, scanline start 762, end 768
Jun 28 14:08:26 leonid-Lenovo-G500 dbus[676]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service'
Jun 28 14:08:26 leonid-Lenovo-G500 systemd[1]: Starting Hostname Service...
Jun 28 14:08:26 leonid-Lenovo-G500 dbus[676]: [system] Successfully activated service 'org.freedesktop.hostname1'
Jun 28 14:08:26 leonid-Lenovo-G500 systemd[1]: Started Hostname Service.
Jun 28 14:08:26 leonid-Lenovo-G500 org.gtk.vfs.Daemon[1159]: ** (process:30582): WARNING **: Couldn't create directory monitor on smb://x-gnome-default-workgroup/. Error: Operation not supported by backend
Jun 28 14:09:10 leonid-Lenovo-G500 gnome-session[1294]: Gtk-Message: GtkDialog mapped without a transient parent. This is discouraged.
Jun 28 14:09:15 leonid-Lenovo-G500 org.gnome.zeitgeist.SimpleIndexer[1159]: ** (zeitgeist-fts:2368): WARNING **: Unable to get info on application://nautilus-autostart.desktop
Jun 28 14:09:58 leonid-Lenovo-G500 URfkill[667]: device_changed_cb: ideapad_bluetooth
Jun 28 14:09:58 leonid-Lenovo-G500 systemd[1]: Starting Load/Save RF Kill Switch Status...
Jun 28 14:09:58 leonid-Lenovo-G500 systemd[1]: Started Load/Save RF Kill Switch Status.
Jun 28 14:09:58 leonid-Lenovo-G500 kernel: [ 2805.143113] usb 1-1.3: USB disconnect, device number 4
Jun 28 14:09:58 leonid-Lenovo-G500 acpid: input device has been disconnected, fd 17
Jun 28 14:09:58 leonid-Lenovo-G500 bluetoothd[880]: (bluetoothd:880): GLib-CRITICAL **: Source ID 144 was not found when attempting to remove it
Jun 28 14:09:58 leonid-Lenovo-G500 URfkill[667]: device_changed_cb: hci0
Jun 28 14:09:58 leonid-Lenovo-G500 bluetoothd[880]: Endpoint unregistered: sender=:1.61 path=/MediaEndpoint/A2DPSource
Jun 28 14:09:58 leonid-Lenovo-G500 systemd-rfkill[1271]: Failed to open device: No such device
Jun 28 14:09:58 leonid-Lenovo-G500 bluetoothd[880]: Endpoint unregistered: sender=:1.61 path=/MediaEndpoint/A2DPSink
Jun 28 14:09:58 leonid-Lenovo-G500 URfkill[667]: removing killswitch idx 5 hci0
Jun 28 14:09:58 leonid-Lenovo-G500 systemd[1]: bluetooth.target: Unit not needed anymore. Stopping.
Jun 28 14:09:58 leonid-Lenovo-G500 systemd[1]: Stopped target Bluetooth.
Jun 28 14:11:22 leonid-Lenovo-G500 kernel: [ 2889.790543] [drm:intel_pipe_update_end [i915]] *ERROR* Atomic update failure on pipe A (start=170069 end=170070) time 189 us, min 763, max 767, scanline start 762, end 769
Jun 28 14:11:32 leonid-Lenovo-G500 dbus-daemon[1088]: Reloaded configuration
Jun 28 14:11:32 leonid-Lenovo-G500 dbus-daemon[1088]: Reloaded configuration
Jun 28 14:11:32 leonid-Lenovo-G500 dbus[676]: [system] Reloaded configuration
Jun 28 14:11:32 leonid-Lenovo-G500 dbus[676]: [system] Reloaded configuration
Jun 28 14:11:40 leonid-Lenovo-G500 URfkill[667]: device_changed_cb: ideapad_bluetooth
Jun 28 14:11:40 leonid-Lenovo-G500 systemd[1]: Starting Load/Save RF Kill Switch Status...
Jun 28 14:11:40 leonid-Lenovo-G500 systemd-udevd[316]: specified group 'phablet' unknown
Jun 28 14:11:40 leonid-Lenovo-G500 systemd[1]: Started Load/Save RF Kill Switch Status.
Jun 28 14:11:40 leonid-Lenovo-G500 kernel: [ 2907.206967] usb 1-1.3: new full-speed USB device number 6 using ehci-pci
Jun 28 14:11:40 leonid-Lenovo-G500 kernel: [ 2907.289353] usb 1-1.3: New USB device found, idVendor=105b, idProduct=e065
Jun 28 14:11:40 leonid-Lenovo-G500 kernel: [ 2907.289357] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jun 28 14:11:40 leonid-Lenovo-G500 kernel: [ 2907.289359] usb 1-1.3: Product: BCM43142A0
Jun 28 14:11:40 leonid-Lenovo-G500 kernel: [ 2907.289360] usb 1-1.3: Manufacturer: Broadcom Corp
Jun 28 14:11:40 leonid-Lenovo-G500 kernel: [ 2907.289362] usb 1-1.3: SerialNumber: F82FA8ED4D02
Jun 28 14:11:40 leonid-Lenovo-G500 URfkill[667]: adding killswitch type 2 idx 6 soft 0 hard 0
Jun 28 14:11:40 leonid-Lenovo-G500 URfkill[667]: Setting device 6 (BLUETOOTH) to unblocked
Jun 28 14:11:40 leonid-Lenovo-G500 URfkill[667]: set_soft: Setting BLUETOOTH to unblocked
Jun 28 14:11:40 leonid-Lenovo-G500 kernel: [ 2907.298247] Bluetooth: hci0: BCM: chip id 70
Jun 28 14:11:40 leonid-Lenovo-G500 kernel: [ 2907.314254] Bluetooth: hci0: leonid-Lenovo-G500
Jun 28 14:11:40 leonid-Lenovo-G500 kernel: [ 2907.314259] Bluetooth: hci0: BCM (001.001.011) build 0312
Jun 28 14:11:40 leonid-Lenovo-G500 mtp-probe: checking bus 1, device 6: "/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3"
Jun 28 14:11:41 leonid-Lenovo-G500 mtp-probe: bus: 1, device: 6 was not an MTP device
Jun 28 14:11:44 leonid-Lenovo-G500 kernel: [ 2910.902369] Bluetooth: hci0: BCM (001.001.011) build 0312
Jun 28 14:11:44 leonid-Lenovo-G500 kernel: [ 2910.918324] Bluetooth: hci0: Broadcom Bluetooth Device (43142)
Jun 28 14:11:44 leonid-Lenovo-G500 systemd[1]: Reached target Bluetooth.
Jun 28 14:11:44 leonid-Lenovo-G500 bluetoothd[880]: Endpoint registered: sender=:1.61 path=/MediaEndpoint/A2DPSource
Jun 28 14:11:44 leonid-Lenovo-G500 bluetoothd[880]: Endpoint registered: sender=:1.61 path=/MediaEndpoint/A2DPSink
Jun 28 14:12:03 leonid-Lenovo-G500 kernel: [ 2930.529932] hid-generic 0005:04E8:7021.0007: unknown main item tag 0x0
Jun 28 14:12:03 leonid-Lenovo-G500 kernel: [ 2930.532096] input: Samsung Bluetooth Keyboard BKB-10 as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3/1-1.3:1.0/bluetooth/hci0/hci0:11/0005:04E8:7021.0007/input/input19
Jun 28 14:12:03 leonid-Lenovo-G500 kernel: [ 2930.534024] hid-generic 0005:04E8:7021.0007: input,hidraw2: BLUETOOTH HID v7.00 Keyboard [Samsung Bluetooth Keyboard BKB-10] on f8:2f:a8:ed:4d:02
Jun 28 14:12:03 leonid-Lenovo-G500 upowerd[1391]: (upowerd:1391): UPower-Linux-WARNING **: treating change event as add on /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.3/1-1.3:1.0/bluetooth/hci0/hci0:11/0005:04E8:7021.0007/power_supply/hid-90:7f:61:10:3c:ab-battery

Revision history for this message

Konrad Zapałowicz (kzapalowicz) wrote on 2016-06-29:

#22

Thanks. The keyboard that yo use is a Bluetooth Classic one and we do not have much issues with those. Of course, there are high chances that we have not been performing our tests using the same model that you use but just for you to know that the issues with BT Classic are something unusual.

I will check if we are able to link the Intel error to the performance penalty.

Best

Revision history for this message

Konrad Zapałowicz (kzapalowicz) wrote on 2016-06-30:

#23

Syslog as recieved from the reporter Edit (737.7 KiB, text/html)

Revision history for this message

Konrad Zapałowicz (kzapalowicz) wrote on 2016-06-30:

#24

Syslog as recieved from the reporter Edit (4.1 MiB, text/html)

Revision history for this message

Leonid (pilleo19) wrote on 2016-07-02:

#25

The intel error only causes disconnects, but not all of them are because of intel error. Often it disconnects by itself, without any other errors.
And there are no lags and repeatings on older bluez, just disconnects. Another thing is that keyboard was designed for android mainly? so it should work with linux in theory.

Revision history for this message

Konrad Zapałowicz (kzapalowicz) wrote on 2016-07-04:

#26

Hey, I have checked with our hardware team and it seems the Intel error that you are experiencing should have nothing to do with Bluetooth performance.

Revision history for this message

Leonid (pilleo19) wrote on 2016-07-04:

#27

Well, you know better than me for sure) But that same mistake appears really often under several linux distributions right before bluetooth keyboard disconnects. Too often to be a coincidence for me.
So, did i activate bluetooth debug options, was my logs as helpful as they can be?

Revision history for this message

Leonid (pilleo19) wrote on 2016-07-06:

#28

So, right now i am working with new (native ubuntu, 5.37) libbluetooth and with bluez 5.27 downloaded from bluez.org, and there are no lags at all, only disconnects. So i guess the reason for lags is in bluez itself.

Revision history for this message

mrns (morrones) wrote on 2016-08-24:

#29

Download full text (4.3 KiB)

I am having this same problem with Logitech m535 bluetooth mouse running Ubuntu 16.04 on a Dell XPS13 9350. Here is the result of the command issued by the OP. Any ideas?

[ 2.817422] Bluetooth: Core ver 2.21
[ 2.817443] Bluetooth: HCI device and connection manager initialized
[ 2.817446] Bluetooth: HCI socket layer initialized
[ 2.817449] Bluetooth: L2CAP socket layer initialized
[ 2.817455] Bluetooth: SCO socket layer initialized
[ 2.826800] Bluetooth: HCI UART driver ver 2.3
[ 2.826803] Bluetooth: HCI UART protocol H4 registered
[ 2.826805] Bluetooth: HCI UART protocol BCSP registered
[ 2.826806] Bluetooth: HCI UART protocol LL registered
[ 2.826807] Bluetooth: HCI UART protocol ATH3K registered
[ 2.826809] Bluetooth: HCI UART protocol Three-wire (H5) registered
[ 2.826843] Bluetooth: HCI UART protocol Intel registered
[ 2.826858] Bluetooth: HCI UART protocol BCM registered
[ 2.826859] Bluetooth: HCI UART protocol QCA registered
[ 2.960951] Bluetooth: hci0: BCM: chip id 110
[ 2.977372] Bluetooth: hci0: BCM4350C5
[ 2.977947] Bluetooth: hci0: BCM (003.006.007) build 0000
[ 2.979162] bluetooth hci0: Direct firmware load for brcm/BCM-0a5c-6412.hcd failed with error -2
[ 2.979168] Bluetooth: hci0: BCM: Patch brcm/BCM-0a5c-6412.hcd not found
[ 4.068669] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 4.068672] Bluetooth: BNEP filters: protocol multicast
[ 4.068676] Bluetooth: BNEP socket layer initialized
[ 6.370274] Bluetooth: RFCOMM TTY layer initialized
[ 6.370281] Bluetooth: RFCOMM socket layer initialized
[ 6.370285] Bluetooth: RFCOMM ver 1.11
[ 43.979826] Bluetooth: hci0: BCM: chip id 110
[ 43.995724] Bluetooth: hci0: BCM4350C5
[ 43.996692] Bluetooth: hci0: BCM (003.006.007) build 0000
[ 43.996725] bluetooth hci0: Direct firmware load for brcm/BCM-0a5c-6412.hcd failed with error -2
[ 43.996727] Bluetooth: hci0: BCM: Patch brcm/BCM-0a5c-6412.hcd not found
[ 368.022543] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[ 368.022566] Bluetooth: HIDP socket layer initialized
[ 370.269291] input: Bluetooth Mouse M336/M337/M535 as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.0/bluetooth/hci0/hci0:12/0005:046D:B014.0002/input/input19
[ 370.270218] hid-generic 0005:046D:B014.0002: input,hidraw1: BLUETOOTH HID v12.00 Mouse [Bluetooth Mouse M336/M337/M535] on 30:52:cb:85:2c:ec
[ 1269.884899] Bluetooth: hci0 urb ffff8802731c2c00 failed to resubmit (2)
[ 1280.112502] Bluetooth: hci0: BCM: chip id 110
[ 1280.128489] Bluetooth: hci0: ChromeLinux_658B
[ 1280.129363] Bluetooth: hci0: BCM (003.006.007) build 0000
[ 1280.129420] bluetooth hci0: Direct firmware load for brcm/BCM-0a5c-6412.hcd failed with error -2
[ 1280.129429] Bluetooth: hci0: BCM: Patch brcm/BCM-0a5c-6412.hcd not found
[ 1284.690426] input: Bluetooth Mouse M336/M337/M535 as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.0/bluetooth/hci0/hci0:11/0005:046D:B014.0003/input/input20
[ 1284.691111] hid-generic 0005:046D:B014.0003: input,hidraw1: BLUETOOTH HID v12.00 Mouse [Bluetooth Mouse M336/M337/M535] on 30:52:cb:85:2c:ec
[ 4944.970706] input: Bluetooth Mouse M336/M337/M535 as /devices/pci0000:00/000...

I am having this same problem with Logitech m535 bluetooth mouse running Ubuntu 16.04 on a Dell XPS13 9350. Here is the result of the command issued by the OP. Any ideas?

[    2.817422] Bluetooth: Core ver 2.21
[    2.817443] Bluetooth: HCI device and connection manager initialized
[    2.817446] Bluetooth: HCI socket layer initialized
[    2.817449] Bluetooth: L2CAP socket layer initialized
[    2.817455] Bluetooth: SCO socket layer initialized
[    2.826800] Bluetooth: HCI UART driver ver 2.3
[    2.826803] Bluetooth: HCI UART protocol H4 registered
[    2.826805] Bluetooth: HCI UART protocol BCSP registered
[    2.826806] Bluetooth: HCI UART protocol LL registered
[    2.826807] Bluetooth: HCI UART protocol ATH3K registered
[    2.826809] Bluetooth: HCI UART protocol Three-wire (H5) registered
[    2.826843] Bluetooth: HCI UART protocol Intel registered
[    2.826858] Bluetooth: HCI UART protocol BCM registered
[    2.826859] Bluetooth: HCI UART protocol QCA registered
[    2.960951] Bluetooth: hci0: BCM: chip id 110
[    2.977372] Bluetooth: hci0: BCM4350C5
[    2.977947] Bluetooth: hci0: BCM (003.006.007) build 0000
[    2.979162] bluetooth hci0: Direct firmware load for brcm/BCM-0a5c-6412.hcd failed with error -2
[    2.979168] Bluetooth: hci0: BCM: Patch brcm/BCM-0a5c-6412.hcd not found
[    4.068669] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[    4.068672] Bluetooth: BNEP filters: protocol multicast
[    4.068676] Bluetooth: BNEP socket layer initialized
[    6.370274] Bluetooth: RFCOMM TTY layer initialized
[    6.370281] Bluetooth: RFCOMM socket layer initialized
[    6.370285] Bluetooth: RFCOMM ver 1.11
[   43.979826] Bluetooth: hci0: BCM: chip id 110
[   43.995724] Bluetooth: hci0: BCM4350C5
[   43.996692] Bluetooth: hci0: BCM (003.006.007) build 0000
[   43.996725] bluetooth hci0: Direct firmware load for brcm/BCM-0a5c-6412.hcd failed with error -2
[   43.996727] Bluetooth: hci0: BCM: Patch brcm/BCM-0a5c-6412.hcd not found
[  368.022543] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[  368.022566] Bluetooth: HIDP socket layer initialized
[  370.269291] input: Bluetooth Mouse M336/M337/M535 as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.0/bluetooth/hci0/hci0:12/0005:046D:B014.0002/input/input19
[  370.270218] hid-generic 0005:046D:B014.0002: input,hidraw1: BLUETOOTH HID v12.00 Mouse [Bluetooth Mouse M336/M337/M535] on 30:52:cb:85:2c:ec
[ 1269.884899] Bluetooth: hci0 urb ffff8802731c2c00 failed to resubmit (2)
[ 1280.112502] Bluetooth: hci0: BCM: chip id 110
[ 1280.128489] Bluetooth: hci0: ChromeLinux_658B
[ 1280.129363] Bluetooth: hci0: BCM (003.006.007) build 0000
[ 1280.129420] bluetooth hci0: Direct firmware load for brcm/BCM-0a5c-6412.hcd failed with error -2
[ 1280.129429] Bluetooth: hci0: BCM: Patch brcm/BCM-0a5c-6412.hcd not found
[ 1284.690426] input: Bluetooth Mouse M336/M337/M535 as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.0/bluetooth/hci0/hci0:11/0005:046D:B014.0003/input/input20
[ 1284.691111] hid-generic 0005:046D:B014.0003: input,hidraw1: BLUETOOTH HID v12.00 Mouse [Bluetooth Mouse M336/M337/M535] on 30:52:cb:85:2c:ec
[ 4944.970706] input: Bluetooth Mouse M336/M337/M535 as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.0/bluetooth/hci0/hci0:12/0005:046D:B014.0004/input/input21
[ 4944.971018] hid-generic 0005:046D:B014.0004: input,hidraw1: BLUETOOTH HID v12.00 Mouse [Bluetooth Mouse M336/M337/M535] on 30:52:cb:85:2c:ec
[    0.206465] [Firmware Bug]: ACPI: BIOS _OSI(Linux) query ignored
[    2.979162] bluetooth hci0: Direct firmware load for brcm/BCM-0a5c-6412.hcd failed with error -2
[    3.084004] brcmfmac 0000:3a:00.0: Direct firmware load for brcm/brcmfmac4350-pcie.txt failed with error -2
[    3.497260] brcmf_c_preinit_dcmds: Firmware version = wl0: Oct 22 2015 06:16:26 version 7.35.180.119 (r594535) FWID 01-e791c176
[   43.977871] brcmfmac 0000:3a:00.0: Direct firmware load for brcm/brcmfmac4350-pcie.txt failed with error -2
[   43.996725] bluetooth hci0: Direct firmware load for brcm/BCM-0a5c-6412.hcd failed with error -2
[   44.410839] brcmf_c_preinit_dcmds: Firmware version = wl0: Oct 22 2015 06:16:26 version 7.35.180.119 (r594535) FWID 01-e791c176
[ 1280.129420] bluetooth hci0: Direct firmware load for brcm/BCM-0a5c-6412.hcd failed with error -2
bluetooth             520192  36 bnep,hidp,btbcm,btqca,btrtl,btusb,hci_uart,rfcomm,btintel

Revision history for this message

Gilles (gillesgalipeau) wrote on 2016-09-20:

#30

I am having the same issue running a bluetooth TECKNET BM306 mouse connecting to the internal bluetooth of an EliteBook 8560W running Ubuntu 16.04.

dmesg:

[54507.549501] hid-generic 0005:0A5C:0001.0008: unknown main item tag 0x0
[54507.549630] input: TECKNET BM306 as /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.6/2-1.6:1.0/bluetooth/hci0/hci0:11/0005:0A5C:0001.0008/input/input30
[54507.549771] hid-generic 0005:0A5C:0001.0008: input,hidraw0: BLUETOOTH HID v1.29 Mouse [TECKNET BM306] on 40:2c:f4:cb:04:7d

Any help would be appreciated.

Revision history for this message

Brian Elliott Finley (finley) wrote on 2016-10-04:

#31

Download full text (5.7 KiB)

I am having the laggy bluetooth mouse issue with a Logitech MX Anywhere 2 and a Lenovo T450s.

The modprobe suggestion from @cribari has worked for me so far, but I've only done 5 minutes of testing at this point, and only one suspend-resume cycle.

Order of operations:
1) suspend/resume/suspend/resume, etc.
2) turn on bluetooth on notebook (mouse was paired weeks ago) [10:19:31 in log below]
3) turn on mouse and experience lag [10:19:36 in log below]
4) execute these commands "sudo modprobe -rv btusb && sleep 1s && sudo modprobe -v btusb" [10:22:33 in log below]
5) mouse is no longer laggy
6) suspend/resume (once)
7) mouse is still not laggy, and appears to be working fine

Oct 4 10:19:31 tbob kernel: [588896.204026] Bluetooth: hci0: read Intel version: 370810011003110e00
Oct 4 10:19:31 tbob kernel: [588896.205078] Bluetooth: hci0: Intel Bluetooth firmware file: intel/ibt-hw-37.8.10-fw-1.10.3.11.e.bseq
Oct 4 10:19:31 tbob kernel: [588896.521054] Bluetooth: hci0: Intel Bluetooth firmware patch completed and activated
Oct 4 10:19:31 tbob systemd[1]: Reached target Bluetooth.
Oct 4 10:19:31 tbob bluetoothd[3094]: Failed to obtain handles for "Service Changed" characteristic
Oct 4 10:19:31 tbob bluetoothd[3094]: Not enough free handles to register service
Oct 4 10:19:31 tbob bluetoothd[3094]: Error adding Link Loss service
Oct 4 10:19:31 tbob bluetoothd[3094]: Not enough free handles to register service
Oct 4 10:19:31 tbob bluetoothd[3094]: message repeated 2 times: [ Not enough free handles to register service]
Oct 4 10:19:31 tbob bluetoothd[3094]: Current Time Service could not be registered
Oct 4 10:19:31 tbob bluetoothd[3094]: gatt-time-server: Input/output error (5)
Oct 4 10:19:31 tbob bluetoothd[3094]: Not enough free handles to register service
Oct 4 10:19:31 tbob bluetoothd[3094]: Not enough free handles to register service
Oct 4 10:19:31 tbob bluetoothd[3094]: Sap driver initialization failed.
Oct 4 10:19:31 tbob bluetoothd[3094]: sap-server: Operation not permitted (1)
Oct 4 10:19:31 tbob bluetoothd[3094]: Endpoint registered: sender=:1.72 path=/MediaEndpoint/A2DPSource
Oct 4 10:19:31 tbob bluetoothd[3094]: Endpoint registered: sender=:1.72 path=/MediaEndpoint/A2DPSink
Oct 4 10:19:36 tbob bluetoothd[3094]: Unable to register GATT service with handle 0x000c for device EA:4C:A8:24:2D:95
Oct 4 10:19:36 tbob bluetoothd[3094]: Unable to register GATT service with handle 0x001b for device EA:4C:A8:24:2D:95
Oct 4 10:19:36 tbob bluetoothd[3094]: Unable to register GATT service with handle 0x003f for device EA:4C:A8:24:2D:95
Oct 4 10:19:37 tbob bluetoothd[3094]: bt_uhid_send: Invalid argument (22)
Oct 4 10:19:37 tbob kernel: [588902.501689] input: MX Anywhere 2 as /devices/virtual/misc/uhid/0005:046D:B013.007C/input/input95
Oct 4 10:19:37 tbob kernel: [588902.502135] hid-generic 0005:046D:B013.007C: input,hidraw4: BLUETOOTH HID v0.07 Keyboard [MX Anywhere 2] on 10:02:B5:80:73:A1
Oct 4 10:19:37 tbob bluetoothd[3094]: message repeated 12 times: [ bt_uhid_send: Invalid argument (22)]
Oct 4 10:22:33 tbob kernel: [589078.328418] usbcore: deregistering interface driver btusb
Oct 4 10:22:33 tbob systemd[1]: bluetooth.target:...

I am having the laggy bluetooth mouse issue with a Logitech MX Anywhere 2 and a Lenovo T450s.

The modprobe suggestion from @cribari has worked for me so far, but I've only done 5 minutes of testing at this point, and only one suspend-resume cycle.

Order of operations:
1) suspend/resume/suspend/resume, etc.
2) turn on bluetooth on notebook (mouse was paired weeks ago) [10:19:31 in log below]
3) turn on mouse and experience lag [10:19:36 in log below]
4) execute these commands "sudo modprobe -rv btusb && sleep 1s && sudo modprobe -v btusb" [10:22:33 in log below]
5) mouse is no longer laggy
6) suspend/resume (once)
7) mouse is still not laggy, and appears to be working fine

Oct  4 10:19:31 tbob kernel: [588896.204026] Bluetooth: hci0: read Intel version: 370810011003110e00
Oct  4 10:19:31 tbob kernel: [588896.205078] Bluetooth: hci0: Intel Bluetooth firmware file: intel/ibt-hw-37.8.10-fw-1.10.3.11.e.bseq
Oct  4 10:19:31 tbob kernel: [588896.521054] Bluetooth: hci0: Intel Bluetooth firmware patch completed and activated
Oct  4 10:19:31 tbob systemd[1]: Reached target Bluetooth.
Oct  4 10:19:31 tbob bluetoothd[3094]: Failed to obtain handles for "Service Changed" characteristic
Oct  4 10:19:31 tbob bluetoothd[3094]: Not enough free handles to register service
Oct  4 10:19:31 tbob bluetoothd[3094]: Error adding Link Loss service
Oct  4 10:19:31 tbob bluetoothd[3094]: Not enough free handles to register service
Oct  4 10:19:31 tbob bluetoothd[3094]: message repeated 2 times: [ Not enough free handles to register service]
Oct  4 10:19:31 tbob bluetoothd[3094]: Current Time Service could not be registered
Oct  4 10:19:31 tbob bluetoothd[3094]: gatt-time-server: Input/output error (5)
Oct  4 10:19:31 tbob bluetoothd[3094]: Not enough free handles to register service
Oct  4 10:19:31 tbob bluetoothd[3094]: Not enough free handles to register service
Oct  4 10:19:31 tbob bluetoothd[3094]: Sap driver initialization failed.
Oct  4 10:19:31 tbob bluetoothd[3094]: sap-server: Operation not permitted (1)
Oct  4 10:19:31 tbob bluetoothd[3094]: Endpoint registered: sender=:1.72 path=/MediaEndpoint/A2DPSource
Oct  4 10:19:31 tbob bluetoothd[3094]: Endpoint registered: sender=:1.72 path=/MediaEndpoint/A2DPSink
Oct  4 10:19:36 tbob bluetoothd[3094]: Unable to register GATT service with handle 0x000c for device EA:4C:A8:24:2D:95
Oct  4 10:19:36 tbob bluetoothd[3094]: Unable to register GATT service with handle 0x001b for device EA:4C:A8:24:2D:95
Oct  4 10:19:36 tbob bluetoothd[3094]: Unable to register GATT service with handle 0x003f for device EA:4C:A8:24:2D:95
Oct  4 10:19:37 tbob bluetoothd[3094]: bt_uhid_send: Invalid argument (22)
Oct  4 10:19:37 tbob kernel: [588902.501689] input: MX Anywhere 2 as /devices/virtual/misc/uhid/0005:046D:B013.007C/input/input95
Oct  4 10:19:37 tbob kernel: [588902.502135] hid-generic 0005:046D:B013.007C: input,hidraw4: BLUETOOTH HID v0.07 Keyboard [MX Anywhere 2] on 10:02:B5:80:73:A1
Oct  4 10:19:37 tbob bluetoothd[3094]: message repeated 12 times: [ bt_uhid_send: Invalid argument (22)]
Oct  4 10:22:33 tbob kernel: [589078.328418] usbcore: deregistering interface driver btusb
Oct  4 10:22:33 tbob systemd[1]: bluetooth.target: Unit not needed anymore. Stopping.
Oct  4 10:22:33 tbob systemd[1]: Stopped target Bluetooth.
Oct  4 10:22:33 tbob bluetoothd[3094]: Endpoint unregistered: sender=:1.72 path=/MediaEndpoint/A2DPSource
Oct  4 10:22:33 tbob bluetoothd[3094]: Endpoint unregistered: sender=:1.72 path=/MediaEndpoint/A2DPSink
Oct  4 10:22:34 tbob kernel: [589079.485951] usbcore: registered new interface driver btusb
Oct  4 10:22:34 tbob kernel: [589079.500790] Bluetooth: hci0: read Intel version: 370810011003110e23
Oct  4 10:22:34 tbob kernel: [589079.500793] Bluetooth: hci0: Intel device is already patched. patch num: 23
Oct  4 10:22:34 tbob bluetoothd[3094]: Failed to obtain handles for "Service Changed" characteristic
Oct  4 10:22:34 tbob bluetoothd[3094]: Not enough free handles to register service
Oct  4 10:22:34 tbob bluetoothd[3094]: Error adding Link Loss service
Oct  4 10:22:34 tbob bluetoothd[3094]: Not enough free handles to register service
Oct  4 10:22:34 tbob bluetoothd[3094]: message repeated 2 times: [ Not enough free handles to register service]
Oct  4 10:22:34 tbob bluetoothd[3094]: Current Time Service could not be registered
Oct  4 10:22:34 tbob bluetoothd[3094]: gatt-time-server: Input/output error (5)
Oct  4 10:22:34 tbob bluetoothd[3094]: Not enough free handles to register service
Oct  4 10:22:34 tbob systemd[1]: Reached target Bluetooth.
Oct  4 10:22:34 tbob bluetoothd[3094]: Not enough free handles to register service
Oct  4 10:22:34 tbob bluetoothd[3094]: Sap driver initialization failed.
Oct  4 10:22:34 tbob bluetoothd[3094]: sap-server: Operation not permitted (1)
Oct  4 10:22:34 tbob bluetoothd[3094]: Endpoint registered: sender=:1.72 path=/MediaEndpoint/A2DPSource
Oct  4 10:22:34 tbob bluetoothd[3094]: Endpoint registered: sender=:1.72 path=/MediaEndpoint/A2DPSink
Oct  4 10:22:37 tbob bluetoothd[3094]: Unable to register GATT service with handle 0x000c for device EA:4C:A8:24:2D:95
Oct  4 10:22:37 tbob bluetoothd[3094]: Unable to register GATT service with handle 0x001b for device EA:4C:A8:24:2D:95
Oct  4 10:22:37 tbob bluetoothd[3094]: Unable to register GATT service with handle 0x003f for device EA:4C:A8:24:2D:95
Oct  4 10:22:37 tbob bluetoothd[3094]: bt_uhid_send: Invalid argument (22)
Oct  4 10:22:37 tbob kernel: [589082.759845] input: MX Anywhere 2 as /devices/virtual/misc/uhid/0005:046D:B013.007D/input/input96
Oct  4 10:22:37 tbob kernel: [589082.760772] hid-generic 0005:046D:B013.007D: input,hidraw4: BLUETOOTH HID v0.07 Keyboard [MX Anywhere 2] on 10:02:B5:80:73:A1
Oct  4 10:22:37 tbob bluetoothd[3094]: message repeated 15 times: [ bt_uhid_send: Invalid argument (22)]

Konrad Zapałowicz (kzapalowicz) on 2017-01-03

tags:

added: papercuts2017

Revision history for this message

albflo (albflo) wrote on 2017-06-25:

#32

I experience the same bug on my Thinkpad Yoga 260 with Ubuntu 16.04.2 and 4.11.7 kernel (the issue happened also on older kernels) with my Thinkpad Compact Bluetooth Keyboard with Trackpoint. The dmesg output looks like this:

[ 4952.521400] lenovo 0005:17EF:6048.0017: unknown main item tag 0x0
[ 4952.521703] input: ThinkPad Compact Bluetooth Keyboard with TrackPoint as /devices/pci0000:00/0000:00:14.0/usb1/1-7/1-7:1.0/bluetooth/hci0/hci0:256/0005:17EF:6048.0017/input/input40
[ 4952.522622] lenovo 0005:17EF:6048.0017: input,hidraw3: BLUETOOTH HID v3.09 Keyboard [ThinkPad Compact Bluetooth Keyboard with TrackPoint] on a4:34:d9:45:7d:7d

Would be perfect if someone has a solution, it's a very annoying bug.

Revision history for this message

Daniel van Vugt (vanvugt) wrote on 2017-06-26:

#33

As far as I know, the lag generally happens using bluetooth scanning (searching). Although scanning should be less disruptive than it is, this seems to be a problem that persists even in current Bluez releases.

You might want to try running 'bluetoothctl' and then typing 'scan off'.

Revision history for this message

Nicolas Jafelle (niko3100) wrote on 2017-07-20:

#34

Having the same issue with Logitech m535 and dell xps 13 9350 with broadcom bcm4350 card.

Revision history for this message

Vasilis (vvlachoudis) wrote on 2017-08-10:

#35

I have the same issue laggy only (no disconnects) with all my bluetooth HID devices, and it is the same on both Ubuntu 17.04 as well fedora core 25.
- Keyboard logitech K810
- Mac keyboard
- Dell BT mouse

The problem did not exist on very old versions of bluez at least with the Mac keyboard.

Restarting bluetooth service seems to fix temporarily.
I've tried also with no success:
- to reduce any possible RF interference (GSM phone, distance etc)
- increase NIce level of bluetooth
- stop upower service

Revision history for this message

Vasilis (vvlachoudis) wrote on 2017-08-10:

#36

I'vvvvvvvve trrrrrrriiiiieeeeeeeed
also "scan off" with no success
(first line from the BT keyboard)

Revision history for this message

Vasilis (vvlachoudis) wrote on 2017-08-10:

#37

I've tried also (something reported to other sites)
sudo sh -c 'echo N > /sys/module/drm_kms_helper/parameters/poll'
with no success

Revision history for this message

Daniel van Vugt (vanvugt) wrote on 2017-08-11:

#38

I have subscribed Konrad to this bug. He told me he had spent time debugging this issue in the past.

Revision history for this message

Vasilis (vvlachoudis) wrote on 2017-08-11:

#39

I've tried changing the power management from auto to on of the bluetooth driver but with no success as well.

If you have ideas on what to check, I am willing to test it. I like a lot the form factor of the small BT keyboard, and I would like to find a solution

Revision history for this message

Vasilis (vvlachoudis) wrote on 2017-08-29:

#40

Update. I replaced my USB bluetooth dongle Bluetooth V4.0 to a very old one V2.0 and since a week now I have no problems with any keyboard or with the mouse.

Revision history for this message

John Tanner (jdtanneratgmaildotcom) wrote on 2017-10-20:

#41

I have a feeling that this is related to bluetooth and wifi coexistence.

https://wireless.wiki.kernel.org/en/users/Drivers/iwlwifi#wi-fibluetooth_coexistence

In essence, bleutooth and wifi operate on similar (the same) frequencies and will interfere. Normally this is handled in the firmware/kernel, but there are problems.

Try this:

https://wiki.debian.org/iwlwifi#Troubleshooting

Revision history for this message

Jeffrey Barrieau (jsbtech) wrote on 2018-11-16:

#42

^I agree with John that this must be related to both Bluetooth and Wifi on at the same time. I don't have an RJ45-USBC dongle to confirm however Im able to see an increase in how often the shuddering occurs when using a 5GHz band wifi vs a 2.4GHz.

I was suggested to update the BIOS by another thread post and that did improve my performance, now the shuddering only begins after some network usage while before the mouse was not usable even when sitting at the desktop.

Reloading the btusb module using modprobe like suggested above also removes the shuddering only to come back after a few moments.

Inserting the supplied BT dongle instead of using the internal radios also eliminates the shuddering but then removes one of the highly valuable USB ports and inevitably will have this port fail on my as this dongle catches on everything when I move the notebook in and out of my backpack. True story =(

Im experience this on both Ubuntu 16.04 and Kubuntu 18.04 on a XPS 13 9350 with latest BIOS
Mouse Im using is the Logitech 2S Anywhere or the 2S Master

Revision history for this message

Daniel van Vugt (vanvugt) wrote on 2018-11-19:

#43

Bluetooth coexistence has also come up in Bluetooth audio bug reports. However it's very much specific to the driver you're using. In particular, Qualcomm Atheros users please see bug 1746164.

If you're using a better supported Intel wifi/Bluetooth chip then I would expect it's not a driver problem but a local environmental problem with 2.4GHz interference, or just poor signal between the laptop and mouse. So you will need to do your own detective work there.

Broadcom chips, I'm not sure about their status but bug 1518408 is still open for them.

Finally, one tip: Close the Settings app before testing Bluetooth quality. While gnome-control-center is open and scanning for devices, it will impede Bluetooth signal quality (bug 1797002).

tags:

added: bionic xenial
removed: papercuts2017 vivid

Ubuntu
linux-firmware package

Bluetooth mouse laggy and erratic

Bug Description

Other bug subscribers

Bug attachments

Remote bug watches

Affects		Status	Importance	Assigned to	Milestone
	bluez (Ubuntu)	Confirmed	Medium	Unassigned
	linux-firmware (Ubuntu)	Confirmed	Medium	Unassigned

Ubuntulinux-firmware package

Bluetooth mouse laggy and erratic

Bug Description

Other bug subscribers

Bug attachments

Remote bug watches

Ubuntu
linux-firmware package