Attachment 1456322 Details for Bug 1596950 – bluetooth mouse disconnect

bluetooth mouse disconnect

bluetoothmouse-disconnect.txt (text/plain), 13.74 KB, created by Chris Murphy on 2018-07-03 19:30:08 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Chris Murphy

Created: 2018-07-03 19:30:08 UTC

Size: 13.74 KB

patch

obsolete

>[chris@f28h ~]$ sudo journalctl --since=-3m | grep -v drm
>[sudo] password for chris: 
>-- Logs begin at Tue 2018-07-03 13:08:27 MDT, end at Tue 2018-07-03 13:17:49 MDT. --
>Jul 03 13:15:12 f28h.local org.gnome.Shell.desktop[1637]: libinput error: client bug: timer event18 debounce: offset negative (-8ms)
>Jul 03 13:15:12 f28h.local org.gnome.Shell.desktop[1637]: libinput error: client bug: timer event18 debounce short: offset negative (-21ms)
>Jul 03 13:16:51 f28h.local kernel: intel_powerclamp: Start idle injection to reduce power
>Jul 03 13:17:06 f28h.local org.gnome.Shell.desktop[1637]: libinput error: client bug: timer event18 debounce: offset negative (-149ms)
>Jul 03 13:17:06 f28h.local org.gnome.Shell.desktop[1637]: libinput error: client bug: timer event18 debounce short: offset negative (-162ms)
>Jul 03 13:17:08 f28h.local gnome-shell[1637]: Object Meta.WindowActor (0x55c12b3f3f30), has been already finalized. Impossible to get any property from it.
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: == Stack trace for context 0x55c12901f3c0 ==
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #0 0x55c129459e30 i   resource:///org/gnome/shell/ui/tweener.js:73 (0x7f83500c7cd0 @ 9)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #1 0x55c129459db0 i   resource:///org/gnome/shell/ui/tweener.js:105 (0x7f83500c7f78 @ 36)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #2 0x55c129459d28 i   resource:///org/gnome/shell/ui/tweener.js:92 (0x7f83500c7de0 @ 52)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #3 0x7ffcddeb90d0 b   resource:///org/gnome/gjs/modules/tweener/tweener.js:208 (0x7f83500d2918 @ 54)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #4 0x7ffcddeb9220 b   resource:///org/gnome/gjs/modules/tweener/tweener.js:337 (0x7f83500d29a0 @ 1626)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #5 0x7ffcddeb92d0 b   resource:///org/gnome/gjs/modules/tweener/tweener.js:350 (0x7f83500d2a28 @ 100)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #6 0x55c129459cb0 i   resource:///org/gnome/gjs/modules/tweener/tweener.js:365 (0x7f83500d2ab0 @ 10)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #7 0x7ffcddeba620 b   resource:///org/gnome/gjs/modules/signals.js:128 (0x7f83500cff78 @ 386)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #8 0x55c129459c20 i   resource:///org/gnome/shell/ui/tweener.js:207 (0x7f83500cf5e8 @ 159)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #9 0x7ffcddebb240 I   resource:///org/gnome/gjs/modules/_legacy.js:82 (0x7f83500b5de0 @ 71)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #10 0x55c129459ba0 i   resource:///org/gnome/shell/ui/tweener.js:182 (0x7f83500cf560 @ 15)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: == Stack trace for context 0x55c12901f3c0 ==
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #0 0x55c129459e30 i   resource:///org/gnome/shell/ui/tweener.js:80 (0x7f83500c7cd0 @ 82)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #1 0x55c129459db0 i   resource:///org/gnome/shell/ui/tweener.js:105 (0x7f83500c7f78 @ 36)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #2 0x55c129459d28 i   resource:///org/gnome/shell/ui/tweener.js:92 (0x7f83500c7de0 @ 52)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #3 0x7ffcddeb90d0 b   resource:///org/gnome/gjs/modules/tweener/tweener.js:208 (0x7f83500d2918 @ 54)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #4 0x7ffcddeb9220 b   resource:///org/gnome/gjs/modules/tweener/tweener.js:337 (0x7f83500d29a0 @ 1626)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #5 0x7ffcddeb92d0 b   resource:///org/gnome/gjs/modules/tweener/tweener.js:350 (0x7f83500d2a28 @ 100)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #6 0x55c129459cb0 i   resource:///org/gnome/gjs/modules/tweener/tweener.js:365 (0x7f83500d2ab0 @ 10)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #7 0x7ffcddeba620 b   resource:///org/gnome/gjs/modules/signals.js:128 (0x7f83500cff78 @ 386)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #8 0x55c129459c20 i   resource:///org/gnome/shell/ui/tweener.js:207 (0x7f83500cf5e8 @ 159)
>Jul 03 13:17:08 f28h.local gnome-shell[1637]: Object Meta.WindowActor (0x55c12b3f3f30), has been already finalized. Impossible to set any property to it.
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #9 0x7ffcddebb240 I   resource:///org/gnome/gjs/modules/_legacy.js:82 (0x7f83500b5de0 @ 71)
>Jul 03 13:17:08 f28h.local org.gnome.Shell.desktop[1637]: #10 0x55c129459ba0 i   resource:///org/gnome/shell/ui/tweener.js:182 (0x7f83500cf560 @ 15)
>Jul 03 13:17:15 f28h.local kernel: intel_powerclamp: Stop forced idle injection
>Jul 03 13:17:17 f28h.local bluetoothd[684]: bluetoothd[684]: src/adapter.c:dev_disconnected() Device 58:1F:AA:EB:B4:6A disconnected, reason 1
>Jul 03 13:17:17 f28h.local bluetoothd[684]: bluetoothd[684]: src/adapter.c:adapter_remove_connection()
>Jul 03 13:17:17 f28h.local bluetoothd[684]: bluetoothd[684]: plugins/policy.c:disconnect_cb() reason 1
>Jul 03 13:17:17 f28h.local bluetoothd[684]: bluetoothd[684]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 58:1F:AA:EB:B4:6A type 0 status 0xe
>Jul 03 13:17:17 f28h.local bluetoothd[684]: bluetoothd[684]: src/device.c:device_bonding_complete() bonding (nil) status 0x0e
>Jul 03 13:17:17 f28h.local bluetoothd[684]: bluetoothd[684]: src/device.c:device_bonding_failed() status 14
>Jul 03 13:17:17 f28h.local bluetoothd[684]: bluetoothd[684]: src/adapter.c:resume_discovery()
>Jul 03 13:17:17 f28h.local bluetoothd[684]: src/adapter.c:dev_disconnected() Device 58:1F:AA:EB:B4:6A disconnected, reason 1
>Jul 03 13:17:17 f28h.local bluetoothd[684]: src/adapter.c:adapter_remove_connection()
>Jul 03 13:17:17 f28h.local bluetoothd[684]: plugins/policy.c:disconnect_cb() reason 1
>Jul 03 13:17:17 f28h.local bluetoothd[684]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr 58:1F:AA:EB:B4:6A type 0 status 0xe
>Jul 03 13:17:17 f28h.local bluetoothd[684]: src/device.c:device_bonding_complete() bonding (nil) status 0x0e
>Jul 03 13:17:17 f28h.local bluetoothd[684]: src/device.c:device_bonding_failed() status 14
>Jul 03 13:17:17 f28h.local bluetoothd[684]: src/adapter.c:resume_discovery()
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: profiles/input/device.c:ctrl_watch_cb() Device 58:1F:AA:EB:B4:6A disconnected
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: profiles/input/device.c:intr_watch_cb() Device 58:1F:AA:EB:B4:6A disconnected
>Jul 03 13:17:18 f28h.local bluetoothd[684]: profiles/input/device.c:ctrl_watch_cb() Device 58:1F:AA:EB:B4:6A disconnected
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: src/service.c:change_state() 0x561ddf99a8b0: device 58:1F:AA:EB:B4:6A profile input-hid state changed: connected -> disconnected (0)
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: profiles/input/device.c:input_device_enter_reconnect_mode() path=/org/bluez/hci0/dev_58_1F_AA_EB_B4_6A reconnect_mode=any
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: profiles/input/device.c:input_device_enter_reconnect_mode() registering auto-reconnect
>Jul 03 13:17:18 f28h.local bluetoothd[684]: profiles/input/device.c:intr_watch_cb() Device 58:1F:AA:EB:B4:6A disconnected
>Jul 03 13:17:18 f28h.local bluetoothd[684]: src/service.c:change_state() 0x561ddf99a8b0: device 58:1F:AA:EB:B4:6A profile input-hid state changed: connected -> disconnected (0)
>Jul 03 13:17:18 f28h.local bluetoothd[684]: profiles/input/device.c:input_device_enter_reconnect_mode() path=/org/bluez/hci0/dev_58_1F_AA_EB_B4_6A reconnect_mode=any
>Jul 03 13:17:18 f28h.local bluetoothd[684]: profiles/input/device.c:input_device_enter_reconnect_mode() registering auto-reconnect
>Jul 03 13:17:18 f28h.local upowerd[1108]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:14.0/usb1/1-7/1-7:1.0/bluetooth/hci0/hci0:256/0005:05AC:030D.0003
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: src/adapter.c:connected_callback() hci0 device 58:1F:AA:EB:B4:6A connected eir_len 13
>Jul 03 13:17:18 f28h.local bluetoothd[684]: src/adapter.c:connected_callback() hci0 device 58:1F:AA:EB:B4:6A connected eir_len 13
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: profiles/input/server.c:connect_event_cb() Incoming connection from 58:1F:AA:EB:B4:6A on PSM 17
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: profiles/input/device.c:input_device_set_channel() idev 0x561ddf9993f0 psm 17
>Jul 03 13:17:18 f28h.local bluetoothd[684]: profiles/input/server.c:connect_event_cb() Incoming connection from 58:1F:AA:EB:B4:6A on PSM 17
>Jul 03 13:17:18 f28h.local bluetoothd[684]: profiles/input/device.c:input_device_set_channel() idev 0x561ddf9993f0 psm 17
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: profiles/input/server.c:confirm_event_cb()
>Jul 03 13:17:18 f28h.local bluetoothd[684]: profiles/input/server.c:confirm_event_cb()
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: profiles/input/server.c:connect_event_cb() Incoming connection from 58:1F:AA:EB:B4:6A on PSM 19
>Jul 03 13:17:18 f28h.local bluetoothd[684]: profiles/input/server.c:connect_event_cb() Incoming connection from 58:1F:AA:EB:B4:6A on PSM 19
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: profiles/input/device.c:input_device_set_channel() idev 0x561ddf9993f0 psm 19
>Jul 03 13:17:18 f28h.local bluetoothd[684]: profiles/input/device.c:input_device_set_channel() idev 0x561ddf9993f0 psm 19
>Jul 03 13:17:18 f28h.local kernel: magicmouse 0005:05AC:030D.0004: unknown main item tag 0x0
>Jul 03 13:17:18 f28h.local kernel: input: mouses as /devices/pci0000:00/0000:00:14.0/usb1/1-7/1-7:1.0/bluetooth/hci0/hci0:512/0005:05AC:030D.0004/input/input20
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: src/service.c:change_state() 0x561ddf99a8b0: device 58:1F:AA:EB:B4:6A profile input-hid state changed: disconnected -> connected (0)
>Jul 03 13:17:18 f28h.local bluetoothd[684]: bluetoothd[684]: plugins/policy.c:service_cb() Added input-hid reconnect 0
>Jul 03 13:17:18 f28h.local bluetoothd[684]: src/service.c:change_state() 0x561ddf99a8b0: device 58:1F:AA:EB:B4:6A profile input-hid state changed: disconnected -> connected (0)
>Jul 03 13:17:18 f28h.local bluetoothd[684]: plugins/policy.c:service_cb() Added input-hid reconnect 0
>Jul 03 13:17:18 f28h.local kernel: magicmouse 0005:05AC:030D.0004: input,hidraw2: BLUETOOTH HID v3.06 Mouse [mouses] on 00:c2:c6:f0:52:57
>Jul 03 13:17:18 f28h.local upowerd[1108]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:14.0/usb1/1-7/1-7:1.0/bluetooth/hci0/hci0:512/0005:05AC:030D.0004
>Jul 03 13:17:18 f28h.local upowerd[1108]: treating change event as add on /sys/devices/pci0000:00/0000:00:14.0/usb1/1-7/1-7:1.0/bluetooth/hci0/hci0:512/0005:05AC:030D.0004/power_supply/hid-58:1f:aa:eb:b4:6a-battery
>Jul 03 13:17:22 f28h.local org.gnome.Shell.desktop[1637]: libinput error: client bug: timer event18 debounce short: offset negative (-0ms)
>Jul 03 13:17:23 f28h.local org.gnome.Shell.desktop[1637]: libinput error: client bug: timer event18 debounce short: offset negative (-4ms)
>Jul 03 13:17:46 f28h.local dbus-daemon[681]: [system] Activating via systemd: service name='net.reactivated.Fprint' unit='fprintd.service' requested by ':1.141' (uid=0 pid=3338 comm="sudo journalctl --since=-3m " label="unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023")
>Jul 03 13:17:46 f28h.local systemd[1]: Starting Fingerprint Authentication Daemon...
>Jul 03 13:17:47 f28h.local dbus-daemon[681]: [system] Successfully activated service 'net.reactivated.Fprint'
>Jul 03 13:17:47 f28h.local systemd[1]: Started Fingerprint Authentication Daemon.
>Jul 03 13:17:47 f28h.local audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=fprintd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
>Jul 03 13:17:48 f28h.local bluetoothd[684]: bluetoothd[684]: profiles/input/device.c:input_device_auto_reconnect() path=/org/bluez/hci0/dev_58_1F_AA_EB_B4_6A, attempt=0
>Jul 03 13:17:48 f28h.local bluetoothd[684]: profiles/input/device.c:input_device_auto_reconnect() path=/org/bluez/hci0/dev_58_1F_AA_EB_B4_6A, attempt=0
>Jul 03 13:17:49 f28h.local audit[3338]: USER_AUTH pid=3338 uid=1000 auid=1000 ses=3 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:authentication grantors=pam_unix acct="chris" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
>Jul 03 13:17:49 f28h.local audit[3338]: USER_ACCT pid=3338 uid=1000 auid=1000 ses=3 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:accounting grantors=pam_unix,pam_localuser acct="chris" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
>Jul 03 13:17:49 f28h.local sudo[3338]:    chris : TTY=pts/2 ; PWD=/home/chris ; USER=root ; COMMAND=/bin/journalctl --since=-3m
>Jul 03 13:17:49 f28h.local audit[3338]: USER_CMD pid=3338 uid=1000 auid=1000 ses=3 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='cwd="/home/chris" cmd=6A6F75726E616C63746C202D2D73696E63653D2D336D terminal=pts/2 res=success'
>Jul 03 13:17:49 f28h.local audit[3338]: CRED_REFR pid=3338 uid=0 auid=1000 ses=3 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:setcred grantors=pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
>Jul 03 13:17:49 f28h.local sudo[3338]: pam_systemd(sudo:session): Cannot create session: Already running in a session
>Jul 03 13:17:49 f28h.local audit[3338]: USER_START pid=3338 uid=0 auid=1000 ses=3 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
>Jul 03 13:17:49 f28h.local sudo[3338]: pam_unix(sudo:session): session opened for user root by (uid=0)
>[chris@f28h ~]$ 
>

Actions: View

Attachments on bug 1596950: 1455679 | 1455680 | 1455681 | 1456322