【以下内容由 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.*]