Fwd: 蓝牙报错 疑问(转寄)

1 view
Skip to first unread message

xiao shen wen(肖盛文)

unread,
May 26, 2023, 3:13:19 AM5/26/23
to us...@atzlinux.com, gz...@googlegroups.com, szlug

【以下内容由 atzlinux 转寄于 LinuxApp 版】
发信人: atzlinux (atzlinux), 信区: LinuxApp
标 题: 蓝牙报错 疑问
发信站: 水木社区 (Fri May 26 15:07:01 2023), 站内

使用 Debian 11.7 最新稳定版内核,出现一个问题就是:

笔记本盒盖休眠一段时间后,再打开笔记本盖唤醒,
使用的蓝牙鼠标,有时候要间隔半分钟到一分多种才能够使用。

之前使用老版本 11.6 的内核没有这个现象。

向 Debian 报告的 bug 情况:

https://bugs.debian.org/cgi-bin/bugreport.cgi?bug=1036015

问题出现时,内核相关报错信息如下:


May 26 14:45:21 atzlinux-d0 kernel: [12077.980552] Bluetooth: hci0: Waiting for firmware download to complete
May 26 14:45:21 atzlinux-d0 kernel: [12077.981058] Bluetooth: hci0: Firmware loaded in 1597030 usecs
May 26 14:45:21 atzlinux-d0 kernel: [12077.981110] Bluetooth: hci0: Waiting for device to boot
May 26 14:45:21 atzlinux-d0 kernel: [12077.993191] Bluetooth: hci0: Device booted in 11836 usecs
May 26 14:45:21 atzlinux-d0 kernel: [12077.993303] Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc
May 26 14:45:21 atzlinux-d0 kernel: [12077.997003] Bluetooth: hci0: Applying Intel DDC parameters completed
May 26 14:45:21 atzlinux-d0 kernel: [12077.997969] Bluetooth: hci0: Reading supported features failed (-16)
May 26 14:45:21 atzlinux-d0 kernel: [12077.997982] Bluetooth: hci0: Telemetry exception format not supported
May 26 14:45:21 atzlinux-d0 kernel: [12077.998952] Bluetooth: hci0: Firmware revision 0.0 build 14 week 44 2021
May 26 14:45:21 atzlinux-d0 bluetoothd[896]: profiles/sap/server.c:sap_server_register() Sap driver initialization failed.
May 26 14:45:21 atzlinux-d0 bluetoothd[896]: sap-server: Operation not permitted (1)
May 26 14:45:21 atzlinux-d0 bluetoothd[896]: Endpoint registered: sender=:1.69 path=/MediaEndpoint/A2DPSink/sbc
May 26 14:45:21 atzlinux-d0 bluetoothd[896]: Endpoint registered: sender=:1.69 path=/MediaEndpoint/A2DPSource/sbc
May 26 14:45:23 atzlinux-d0 wpa_supplicant[648]: wlp1s0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=CN
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.6791] policy: auto-activating connection 'wr' (5704f31d-d6a5-4953-8c76-c1295ae4125f)
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.6829] device (wlp1s0): Activation: starting connection 'wr' (5704f31d-d6a5-4953-8c76-c1295ae4125f)
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.6835] device (wlp1s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.6851] manager: NetworkManager state is now CONNECTING
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.6902] device (wlp1s0): set-hw-addr: reset MAC address to A0:C5:89:47:0C:0C (preserve)
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.6947] device (wlp1s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.6959] device (wlp1s0): Activation: (wifi) access point 'wr' has security, but secrets are required.
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.6960] device (wlp1s0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.6968] sup-iface[1b6221ca8df70b5c,5,wlp1s0]: wps: type pbc start...
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.7096] device (wlp1s0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.7105] device (wlp1s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.7110] device (wlp1s0): Activation: (wifi) connection 'wr' has security, and secrets exist. No new secrets needed.
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.7111] Config: added 'ssid' value 'wr'
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.7111] Config: added 'scan_ssid' value '1'
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.7111] Config: added 'bgscan' value 'simple:30:-65:300'
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.7112] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK'
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.7112] Config: added 'psk' value '<hidden>'
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.7803] device (wlp1s0): supplicant interface state: disconnected -> scanning
May 26 14:45:23 atzlinux-d0 NetworkManager[633]: <info> [1685083523.7805] device (p2p-dev-wlp1s0): supplicant management interface state: disconnected -> scanning
May 26 14:45:24 atzlinux-d0 kernel: [12081.387202] wlp1s0: authenticate with 44:f9:71:69:42:20
May 26 14:45:24 atzlinux-d0 wpa_supplicant[648]: wlp1s0: SME: Trying to authenticate with 44:f9:71:69:42:20 (SSID='wr' freq=5745 MHz)
May 26 14:45:24 atzlinux-d0 kernel: [12081.397986] wlp1s0: send auth to 44:f9:71:69:42:20 (try 1/3)
May 26 14:45:24 atzlinux-d0 NetworkManager[633]: <info> [1685083524.8170] device (wlp1s0): supplicant interface state: scanning -> authenticating
May 26 14:45:24 atzlinux-d0 NetworkManager[633]: <info> [1685083524.8171] device (p2p-dev-wlp1s0): supplicant management interface state: scanning -> authenticating
May 26 14:45:24 atzlinux-d0 wpa_supplicant[648]: wlp1s0: Trying to associate with 44:f9:71:69:42:20 (SSID='wr' freq=5745 MHz)
May 26 14:45:24 atzlinux-d0 kernel: [12081.412005] wlp1s0: authenticated
May 26 14:45:24 atzlinux-d0 kernel: [12081.416612] wlp1s0: associate with 44:f9:71:69:42:20 (try 1/3)
May 26 14:45:24 atzlinux-d0 NetworkManager[633]: <info> [1685083524.8330] device (wlp1s0): supplicant interface state: authenticating -> associating
May 26 14:45:24 atzlinux-d0 NetworkManager[633]: <info> [1685083524.8331] device (p2p-dev-wlp1s0): supplicant management interface state: authenticating -> associating
May 26 14:45:24 atzlinux-d0 kernel: [12081.424387] wlp1s0: RX AssocResp from 44:f9:71:69:42:20 (capab=0x1011 status=0 aid=2)
May 26 14:45:24 atzlinux-d0 wpa_supplicant[648]: wlp1s0: Associated with 44:f9:71:69:42:20
May 26 14:45:24 atzlinux-d0 wpa_supplicant[648]: wlp1s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
May 26 14:45:24 atzlinux-d0 kernel: [12081.435968] wlp1s0: associated
May 26 14:45:24 atzlinux-d0 NetworkManager[633]: <info> [1685083524.8610] device (wlp1s0): supplicant interface state: associating -> associated
May 26 14:45:24 atzlinux-d0 NetworkManager[633]: <info> [1685083524.8611] device (p2p-dev-wlp1s0): supplicant management interface state: associating -> associated
May 26 14:45:24 atzlinux-d0 wpa_supplicant[648]: wlp1s0: WPA: Key negotiation completed with 44:f9:71:69:42:20 [PTK=CCMP GTK=CCMP]
May 26 14:45:24 atzlinux-d0 wpa_supplicant[648]: wlp1s0: CTRL-EVENT-CONNECTED - Connection to 44:f9:71:69:42:20 completed [id=0 id_str=]
May 26 14:45:24 atzlinux-d0 kernel: [12081.541708] IPv6: ADDRCONF(NETDEV_CHANGE): wlp1s0: link becomes ready
May 26 14:45:24 atzlinux-d0 kernel: [12081.545063] wlp1s0: Limiting TX power to 35 (35 - 0) dBm as advertised by 44:f9:71:69:42:20
May 26 14:45:24 atzlinux-d0 wpa_supplicant[648]: wlp1s0: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=-51 noise=9999 txrate=6000
May 26 14:45:24 atzlinux-d0 NetworkManager[633]: <info> [1685083524.9659] device (wlp1s0): supplicant interface state: associated -> completed
May 26 14:45:24 atzlinux-d0 NetworkManager[633]: <info> [1685083524.9661] device (wlp1s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "wr"
May 26 14:45:24 atzlinux-d0 wpa_supplicant[648]: wlp1s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-51 noise=9999 txrate=6000
May 26 14:45:24 atzlinux-d0 NetworkManager[633]: <info> [1685083524.9663] device (p2p-dev-wlp1s0): supplicant management interface state: associated -> completed
May 26 14:45:24 atzlinux-d0 wpa_supplicant[648]: wlp1s0: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=-51 noise=9999 txrate=6000
May 26 14:45:24 atzlinux-d0 NetworkManager[633]: <info> [1685083524.9677] device (wlp1s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
May 26 14:45:24 atzlinux-d0 NetworkManager[633]: <info> [1685083524.9701] dhcp4 (wlp1s0): activation: beginning transaction (timeout in 45 seconds)
May 26 14:45:24 atzlinux-d0 avahi-daemon[629]: Joining mDNS multicast group on interface wlp1s0.IPv4 with address 192.168.159.100.

May 26 14:45:24 atzlinux-d0 avahi-daemon[629]: New relevant interface wlp1s0.IPv4 for mDNS.
May 26 14:45:24 atzlinux-d0 avahi-daemon[629]: Registering new address record for 192.168.159.100 on wlp1s0.IPv4.
May 26 14:45:24 atzlinux-d0 avahi-daemon[629]: Joining mDNS multicast group on interface wlp1s0.IPv6 with address fe80::a232:6cb1:8a32:1558.
May 26 14:45:24 atzlinux-d0 avahi-daemon[629]: New relevant interface wlp1s0.IPv6 for mDNS.
May 26 14:45:24 atzlinux-d0 avahi-daemon[629]: Registering new address record for fe80::a232:6cb1:8a32:1558 on wlp1s0.*.
May 26 14:45:25 atzlinux-d0 NetworkManager[633]: <info> [1685083525.0590] dhcp4 (wlp1s0): state changed unknown -> bound, address=192.168.127.77
May 26 14:45:25 atzlinux-d0 avahi-daemon[629]: Registering new address record for 192.168.127.77 on wlp1s0.IPv4.
May 26 14:45:25 atzlinux-d0 NetworkManager[633]: <info> [1685083525.0671] device (wlp1s0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
May 26 14:45:25 atzlinux-d0 NetworkManager[633]: <info> [1685083525.0823] device (wlp1s0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
May 26 14:45:25 atzlinux-d0 NetworkManager[633]: <info> [1685083525.0840] device (wlp1s0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
May 26 14:45:25 atzlinux-d0 NetworkManager[633]: <info> [1685083525.0875] manager: NetworkManager state is now CONNECTED_LOCAL
May 26 14:45:25 atzlinux-d0 NetworkManager[633]: <info> [1685083525.0976] manager: NetworkManager state is now CONNECTED_SITE
May 26 14:45:25 atzlinux-d0 NetworkManager[633]: <info> [1685083525.0983] policy: set 'wr' (wlp1s0) as default for IPv4 routing and DNS
May 26 14:45:25 atzlinux-d0 NetworkManager[633]: <info> [1685083525.1209] device (wlp1s0): Activation: successful, device activated.
May 26 14:45:25 atzlinux-d0 NetworkManager[633]: <info> [1685083525.1256] manager: NetworkManager state is now CONNECTED_GLOBAL
May 26 14:45:26 atzlinux-d0 avahi-daemon[629]: Leaving mDNS multicast group on interface wlp1s0.IPv6 with address fe80::a232:6cb1:8a32:1558.
May 26 14:45:26 atzlinux-d0 avahi-daemon[629]: Joining mDNS multicast group on interface wlp1s0.IPv6 with address 2409:8d80:9002:9408:6e25:b8b3:1da8:609b.
May 26 14:45:26 atzlinux-d0 NetworkManager[633]: <info> [1685083526.5076] policy: set 'wr' (wlp1s0) as default for IPv6 routing and DNS
May 26 14:45:26 atzlinux-d0 avahi-daemon[629]: Registering new address record for 2409:8d80:9002:9408:6e25:b8b3:1da8:609b on wlp1s0.*.
May 26 14:45:26 atzlinux-d0 avahi-daemon[629]: Withdrawing address record for fe80::a232:6cb1:8a32:1558 on wlp1s0.
May 26 14:45:28 atzlinux-d0 avahi-daemon[629]: Registering new address record for 2409:8d80:9002:9408:1f44:3bf2:5e21:283f on wlp1s0.*.
May 26 14:45:28 atzlinux-d0 CRON[12877]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
May 26 14:45:35 atzlinux-d0 systemd[1]: NetworkManager-dispatcher.service: Succeeded.
May 26 14:45:52 atzlinux-d0 bluetoothd[896]: profiles/deviceinfo/deviceinfo.c:read_pnpid_cb() Error reading PNP_ID value: Request attribute has encountered an unlikely error
May 26 14:45:52 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:proto_mode_read_cb() Protocol Mode characteristic read failed: Request attribute has encountered an unlikely error
May 26 14:45:52 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_reference_cb() Read Report Reference descriptor failed: Request attribute has encountered an unlikely error
May 26 14:45:52 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_reference_cb() Read Report Reference descriptor failed: Request attribute has encountered an unlikely error
May 26 14:45:52 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_map_read_cb() Report Map read failed: Request attribute has encountered an unlikely error
May 26 14:45:52 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:info_read_cb() HID Information read failed: Request attribute has encountered an unlikely error

May 26 14:46:02 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 bluetoothd[896]: profiles/input/hog-lib.c:report_value_cb() bt_uhid_send: Invalid argument (22)
May 26 14:46:03 atzlinux-d0 kernel: [12119.721352] input: RAPOO BT4.0 Mouse as /devices/virtual/misc/uhid/0005:000E:3412.0008/input/input31
May 26 14:46:03 atzlinux-d0 kernel: [12119.721926] input: RAPOO BT4.0 Mouse Consumer Control as /devices/virtual/misc/uhid/0005:000E:3412.0008/input/input32
May 26 14:46:03 atzlinux-d0 kernel: [12119.722269] hid-generic 0005:000E:3412.0008: input,hidraw1: BLUETOOTH HID v67.01 Mouse [RAPOO BT4.0 Mouse] on a0:c5:89:47:0c:10

哪位对蓝牙有研究的,欢迎帮忙分析下这个问题,谢谢!


--
铜豌豆 Linux
https://www.atzlinux.com
基于 Debian 的 Linux 中文桌面操作系统

铜豌豆软件源:Debian 系发行版 中文软件
https://www.atzlinux.com/allpackages.htm


※ 来源:·水木社区 mysmth.net·[FROM: 218.204.252.*]


OpenPGP_0x2F338C7DC7909957.asc
OpenPGP_signature
Reply all
Reply to author
Forward
0 new messages