New issue
Advanced search Search tips
Note: Color blocks (like or ) mean that a user may not be available. Tooltip shows the reason.

Issue 635120 link

Starred by 2 users

Issue metadata

Status: Duplicate
Merged: issue 472604
Owner: ----
Closed: Aug 2016
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 2
Type: Bug



Sign in to add a comment

Bluetooth mouse disconnects and Chromebook reports Bluetooth disabled

Project Member Reported by edoan@chromium.org, Aug 5 2016

Issue description

Google Chrome	53.0.2785.47 (Official Build) beta (64-bit)
Revision	0
Platform	8530.43.0 (Official Build) beta-channel samus

My Apple Magic Mouse keeps disconnecting from Samus. When I open the system tray menu, it reports Bluetooth is disabled. I have to re-enable Bluetooth (using built-in trackpad), then the mouse will reconnect after a few moments. It appears the BT adapter is "disconnected"? See logs below and attached.


2016-08-05T16:12:15.604383-05:00 INFO kernel: [ 5140.640495] usb 1-8: USB disconnect, device number 4
2016-08-05T16:12:15.609417-05:00 NOTICE kernel: [ 5140.645927] audit: type=1400 audit(1470431535.608:245): avc:  denied  { getattr } for  pid=28 comm="kdevtmpfs" path="/input/event13" dev="devtmpfs" ino=377260 scontext=u:r:kernel:s0 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1
2016-08-05T16:12:15.609448-05:00 NOTICE kernel: [ 5140.646008] audit: type=1400 audit(1470431535.608:246): avc:  denied  { unlink } for  pid=28 comm="kdevtmpfs" name="event13" dev="devtmpfs" ino=377260 scontext=u:r:kernel:s0 tcontext=u:object_r:device:s0 tclass=chr_file permissive=1
2016-08-05T16:12:15.630951-05:00 INFO bluetoothd[3426]: Endpoint unregistered: sender=:1.28 path=/org/chromium/Cras/Bluetooth/A2DPSource
2016-08-05T16:12:15.631925-05:00 NOTICE dbus[628]: [system] Rejected send message, 3 matched rules; type="method_return", sender=":1.28" (uid=600 pid=3476 comm="/usr/bin/cras --dsp_config=/etc/cras/dsp.samus.ori") interface="(unset)" member="(unset)" error name="(unset)" requested_reply="0" destination=":1.27" (uid=218 pid=3426 comm="/usr/libexec/bluetooth/bluetoothd --nodetach ")
2016-08-05T16:12:15.631949-05:00 NOTICE dbus[628]: [system] Rejected send message, 3 matched rules; type="method_return", sender=":1.28" (uid=600 pid=3476 comm="/usr/bin/cras --dsp_config=/etc/cras/dsp.samus.ori") interface="(unset)" member="(unset)" error name="(unset)" requested_reply="0" destination=":1.27" (uid=218 pid=3426 comm="/usr/libexec/bluetooth/bluetoothd --nodetach ")
2016-08-05T16:12:16.048384-05:00 INFO kernel: [ 5141.084456] usb 1-8: new full-speed USB device number 17 using xhci_hcd
2016-08-05T16:12:16.213376-05:00 INFO kernel: [ 5141.249631] usb 1-8: New USB device found, idVendor=8087, idProduct=07dc
2016-08-05T16:12:16.213401-05:00 INFO kernel: [ 5141.249655] usb 1-8: New USB device strings: Mfr=0, Product=0, SerialNumber=0
2016-08-05T16:12:16.213410-05:00 NOTICE kernel: [ 5141.249848] audit: type=1400 audit(1470431536.212:247): avc:  denied  { write } for  pid=28 comm="kdevtmpfs" name="001" dev="devtmpfs" ino=1065 scontext=u:r:kernel:s0 tcontext=u:object_r:device:s0 tclass=dir permissive=1
2016-08-05T16:12:16.213418-05:00 NOTICE kernel: [ 5141.249906] audit: type=1400 audit(1470431536.212:248): avc:  denied  { add_name } for  pid=28 comm="kdevtmpfs" name="017" scontext=u:r:kernel:s0 tcontext=u:object_r:device:s0 tclass=dir permissive=1
2016-08-05T16:12:16.230550-05:00 INFO kernel: [ 5141.267370] Bluetooth: hci0: read Intel version: 3707100180012d0d25
2016-08-05T16:12:16.230563-05:00 INFO kernel: [ 5141.267381] Bluetooth: hci0: Intel device is already patched. patch num: 25
2016-08-05T16:12:16.236709-05:00 ERR laptop-mode[17136]: Couldn't acquire lock. Retrying.... PID is 17073\n
2016-08-05T16:12:16.266627-05:00 INFO laptop-mode[17141]: Laptop mode 
2016-08-05T16:12:16.267493-05:00 INFO laptop-mode[17142]: enabled, not active
2016-08-05T16:12:16.279885-05:00 ERR bluetoothd[3426]: Failed to obtain handles for "Service Changed" characteristic
2016-08-05T16:12:16.338342-05:00 DEBUG kernel: [ 5141.373528] SELinux: initialized (dev proc, type proc), uses genfs_contexts
2016-08-05T16:12:16.353253-05:00 INFO bluetoothd[3426]: Endpoint registered: sender=:1.28 path=/org/chromium/Cras/Bluetooth/A2DPSource
2016-08-05T16:12:16.353271-05:00 INFO bluetoothd[3426]: Player registered: sender=:1.28 path=/org/chromium/Cras/Bluetooth/DefaultPlayer
2016-08-05T16:12:16.353275-05:00 ERR cras_server[3476]: RegisterProfile returned error: org.bluez.Error.AlreadyExists
2016-08-05T16:12:16.353279-05:00 ERR cras_server[3476]: RegisterProfile returned error: org.bluez.Error.AlreadyExists
2016-08-05T16:12:16.680981-05:00 INFO laptop-mode[17524]: Laptop mode 
2016-08-05T16:12:16.681564-05:00 INFO laptop-mode[17525]: enabled, not active
2016-08-05T16:12:17.074738-05:00 ERR laptop-mode[18097]: Couldn't acquire lock. Retrying.... PID is 17072\n
2016-08-05T16:12:17.129788-05:00 INFO laptop-mode[18113]: Laptop mode 
2016-08-05T16:12:17.131965-05:00 INFO laptop-mode[18114]: enabled, not active
2016-08-05T16:12:51.987729-05:00 DEBUG kernel: [ 5177.053096] SELinux: initialized (dev proc, type proc), uses genfs_contexts
2016-08-05T16:12:53.546507-05:00 ERR bluetoothd[3426]: Can't get HIDP connection info
2016-08-05T16:13:06.603231-05:00 ERR bluetoothd[3426]: connect error: Connection reset by peer (104)
2016-08-05T16:13:16.317105-05:00 DEBUG kernel: [ 5201.401351] SELinux: initialized (dev proc, type proc), uses genfs_contexts
2016-08-05T16:13:17.165334-05:00 DEBUG kernel: [ 5202.251478] SELinux: initialized (dev proc, type proc), uses genfs_contexts
2016-08-05T16:13:31.996633-05:00 DEBUG kernel: [ 5217.095106] SELinux: initialized (dev proc, type proc), uses genfs_contexts
2016-08-05T16:14:12.007451-05:00 DEBUG kernel: [ 5257.138270] SELinux: initialized (dev proc, type proc), uses genfs_contexts
2016-08-05T16:14:34.221222-05:00 INFO periodic_scheduler[19297]: crash_sender: running /sbin/crash_sender
2016-08-05T16:14:34.225882-05:00 INFO periodic_scheduler[19302]: cros-machine-id-regen: running /usr/sbin/cros-machine-id-regen -r periodic -t 21600
2016-08-05T16:14:34.235256-05:00 NOTICE cros-machine-id-regen[19327]: Not regenerating since we did so 4027 seconds ago.
2016-08-05T16:14:34.235887-05:00 INFO periodic_scheduler[19329]: cros-machine-id-regen: job completed
2016-08-05T16:14:34.241219-05:00 INFO periodic_scheduler[19336]: crash_sender: job completed
2016-08-05T16:14:52.015382-05:00 DEBUG kernel: [ 5297.177968] SELinux: initialized (dev proc, type proc), uses genfs_contexts
2016-08-05T16:15:17.163336-05:00 DEBUG kernel: [ 5322.346378] SELinux: initialized (dev proc, type proc), uses genfs_contexts
2016-08-05T16:15:18.520235-05:00 DEBUG kernel: [ 5323.701715] SELinux: initialized (dev proc, type proc), uses genfs_contexts
2016-08-05T16:15:18.683335-05:00 INFO kernel: [ 5323.867906] rt5677 i2c-RT5677CE:00: DSP VAD: enable=0, activity=0
2016-08-05T16:15:18.683346-05:00 INFO kernel: [ 5323.868126] rt5677 i2c-RT5677CE:00: DSP VAD: enable=1, activity=0
2016-08-05T16:15:18.712442-05:00 ERR cras_server[3476]: No chmap queried!
2016-08-05T16:15:18.714331-05:00 DEBUG kernel: [ 5323.899285] SELinux: initialized (dev proc, type proc), uses genfs_contexts
2016-08-05T16:15:18.723339-05:00 INFO kernel: [ 5323.907801] rt5677 i2c-RT5677CE:00: Requested rt5677_elf_vad (82584)
2016-08-05T16:15:18.723353-05:00 INFO kernel: [ 5323.907810] rt5677 i2c-RT5677CE:00: Load 0x23c0 bytes to 0x5ffc0000
2016-08-05T16:15:18.735047-05:00 INFO chrom[1919]: --ppapi-flash-path=/opt/google/chrome/pepper/libpepflashplayer.so --ppapi-flash-version=22.0.0.209-r1 --ppapi-flash-args=enable_hw_video_decode=1 --ui-prioritize-in-gpu-process --use-gl=egl --enable-native-gpu-memory-buffers --gpu-sandbox-failures-fatal=yes --enable-arc --enable-logging --log-level=1 --use-cras --enable-wayland-server --user-data-dir=/home/chronos --max-unused-resource-memory-usage-percentage=5 --login-profile=user --enable-natural-scroll-default --has-chromeos-keyboard --default-wallpaper-large=/usr/share/chromeos-assets/wallpaper/default_large.jpg --default-wallpaper-small=/usr/share/chromeos-assets/wallpaper/default_small.jpg --child-wallpaper-large=/usr/share/chromeos-assets/wallpaper/child_large.jpg --child-wallpaper-small=/usr/share/chromeos-assets/wallpaper/child_small.jpg --guest-wallpaper-large=/usr/share/chromeos-assets/wallpaper/guest_large.jpg --guest-wallpaper-small=/usr/share/chromeos-assets/wallpaper/guest_small.jpg --enable-prefixed-encrypted-media --enterprise-enrollment-initial-modulus=15 --enterprise-enrollment-modulus-limit=19 --vmodule=screen_locker=2,webui_screen_locker=2,lock_state_controller=2,webui_login_view=2,power_button_observer=2,*ui/display/chromeos*=1,*ash/display*=1,*ui/ozone*=1,*zygote*=1,*plugin*=2,*chromeos/login/*=1,*arc/*=1 --login-manager --first-exec-after-boot: set rlimit success
2016-08-05T16:15:18.746337-05:00 INFO kernel: [ 5323.930972] rt5677 i2c-RT5677CE:00: Load 0xa600 bytes to 0x5ffc9800
2016-08-05T16:15:18.824367-05:00 INFO kernel: [ 5324.009047] rt5677 i2c-RT5677CE:00: Load 0x16a bytes to 0x5ffe0000
2016-08-05T16:15:18.825388-05:00 INFO kernel: [ 5324.010134] rt5677 i2c-RT5677CE:00: Load 0x6 bytes to 0x5ffe017c
2016-08-05T16:15:18.825403-05:00 INFO kernel: [ 5324.010402] rt5677 i2c-RT5677CE:00: Load 0xb bytes to 0x5ffe019c
2016-08-05T16:15:18.826364-05:00 INFO kernel: [ 5324.011456] rt5677 i2c-RT5677CE:00: Load 0x3 bytes to 0x5ffe01bc
2016-08-05T16:15:18.827350-05:00 INFO kernel: [ 5324.011649] rt5677 i2c-RT5677CE:00: Load 0x6 bytes to 0x5ffe01dc
2016-08-05T16:15:18.827377-05:00 INFO kernel: [ 5324.011865] rt5677 i2c-RT5677CE:00: Load 0x4 bytes to 0x5ffe01f8
2016-08-05T16:15:18.827383-05:00 INFO kernel: [ 5324.011981] rt5677 i2c-RT5677CE:00: Load 0x17 bytes to 0x5ffe01fc
2016-08-05T16:15:18.827387-05:00 INFO kernel: [ 5324.012186] rt5677 i2c-RT5677CE:00: Load 0x6 bytes to 0x5ffe021c
2016-08-05T16:15:18.827392-05:00 INFO kernel: [ 5324.012381] rt5677 i2c-RT5677CE:00: Load 0x135 bytes to 0x5ffe0240
2016-08-05T16:15:18.828338-05:00 INFO kernel: [ 5324.013020] rt5677 i2c-RT5677CE:00: Load 0x6f21 bytes to 0x5ffe0520
2016-08-05T16:15:18.875346-05:00 INFO kernel: [ 5324.060430] rt5677 i2c-RT5677CE:00: DSP VAD: enable=1, activity=1
2016-08-05T16:15:20.775135-05:00 INFO kernel: [ 5325.960703] rt5677 i2c-RT5677CE:00: DSP VAD: enable=0, activity=1
2016-08-05T16:16:32.029360-05:00 DEBUG kernel: [ 5397.273700] SELinux: initialized (dev proc, type proc), uses genfs_contexts
2016-08-05T16:17:12.043350-05:00 DEBUG kernel: [ 5437.319558] SELinux: initialized (dev proc, type proc), uses genfs_contexts
2016-08-05T16:17:12.577040-05:00 ERR kernel: [ 5437.853455] magicmouse 0005:05AC:030D.0005: unknown main item tag 0x0
2016-08-05T16:17:12.675338-05:00 INFO kernel: [ 5437.952704] input: edoan’s mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-8/1-8:1.0/bluetooth/hci0/hci0:256:4/0005:05AC:030D.0005/input/input14
2016-08-05T16:17:12.676324-05:00 INFO kernel: [ 5437.953032] magicmouse 0005:05AC:030D.0005: input,hidraw3: BLUETOOTH HID v0.84 Mouse [edoan’s mouse] on 6c:29:95:42:29:4d
2016-08-05T16:17:12.736660-05:00 NOTICE root[19779]: /opt/google/input/device_added 
2016-08-05T16:17:12.746660-05:00 NOTICE root[19795]: /opt/google/input/device_added /dev/input/event13


 
debug-logs_20160805-163342.tgz
4.3 MB Download
Cc: armansito@chromium.org adlr@chromium.org
See comment 15: https://bugs.chromium.org/p/chromium/issues/detail?id=472604

+Aldr and armansito - Is this a dupe of the above?
Mergedinto: 472604
Status: Duplicate (was: Untriaged)

Sign in to add a comment