I'm working through an issue with Bluetooth LE Audio with a new headset
(Creative Zen Hybrid Pro). The end result of which is no audio
input/output device is created.
With the generous help from Pauli (cc'ed) of the pipewire team, we can
see the headset connection establishes OK, but then appears to hit a
bug in the adapter's firmware (MediaTek MT7922), which triggers a
coredump and restarts.
Full logs and other context can be found at
https://gitlab.freedesktop.org/pipewire/pipewire/-/issues/3666#note_2179246
... but as an overview of logs via btmon:
= Note: Linux version 6.6.1 (x86_64)
0.069372
= Note: Bluetooth subsystem version 2.22
0.069374
= New Index: 14:AC:60:46:9E:9C (Primary,USB,hci3) [hci3]
0.069375
= Open Index: 14:AC:60:46:9E:9C [hci3]
0.069375
= Index Info: 14:AC:60:46:9E:9C (MediaTek, Inc.) [hci3]
0.069376
@ MGMT Open: bluetoothd (privileged) version 1.22 {0x0001}
0.069377
@ MGMT Open: bluetoothctl version 1.22 {0x0002}
0.069378
> ACL Data RX: Handle 512 flags 0x01 dlen 24 #142 [hci3]
12.183718
ATT: Handle Value Notification (0x1b) len 46
< HCI Command: LE Set Connected... (0x08|0x0062) plen 24 #143 [hci3]
12.183932
CIG ID: 0x00
> HCI Event: Command Complete (0x0e) plen 8 #144 [hci3]
12.184357
LE Set Connected Isochronous Group Parameters (0x08|0x0062) ncmd
1
[ ... snip ... ]
> HCI Event: Command Status (0x0f) plen 4 #170 [hci3]
12.664283
LE Create Connected Isochronous Stream (0x08|0x0064) ncmd 1
[ ... snip ... ]
> HCI Event: LE Meta Event (0x3e) plen 29 #186 [hci3]
13.203205
LE Connected Isochronous Stream Established (0x19)
< HCI Command: LE Setup Isochron.. (0x08|0x006e) plen 13 #187 [hci3]
13.203326
Handle: 1024
@ RAW Open: btmon (privileged) version 2.22 {0x0003}
13.203504
@ RAW Close: btmon {0x0003}
13.203512
> HCI Event: Command Complete (0x0e) plen 6 #188 [hci3]
13.203576
LE Setup Isochronous Data Path (0x08|0x006e) ncmd 1
< HCI Command: LE Setup Isochron.. (0x08|0x006e) plen 13 #189 [hci3]
13.203606
Handle: 1024
> HCI Event: Command Complete (0x0e) plen 6 #190 [hci3]
13.203929
LE Setup Isochronous Data Path (0x08|0x006e) ncmd 1
< ACL Data TX: Handle 512 flags 0x00 dlen 10 #191 [hci3]
13.214209
ATT: Write Command (0x52) len 5
= Vendor Diagnostic (len 72) [hci3]
13.323162
6f fc 44 00 3c 41 53 53 45 52 54 3e 20 2e 2e 2f o.D.<ASSERT>
../
[ ... snip ... ]
= Vendor Diagnostic (len 19) [hci3]
14.095706
6f fc 0f 00 0a 3b 63 6f 72 65 64 75 6d 70 20 65 o....;coredump
e
[ ... snip ... ]
= bluetoothd: profiles/audio/transport.c:link_set_state() Unable...
14.143326
= Close Index: 14:AC:60:46:9E:9C [hci3]
14.188719
@ MGMT Event: Index Removed (0x0005) plen 0 {0x0001} [hci3]
14.188726
@ MGMT Event: Index Removed (0x0005) plen 0 {0x0002} [hci3]
14.188726
= Delete Index: 14:AC:60:46:9E:9C [hci3]
14.188733
= bluetoothd: profiles/audio/micp.c:micp_detached() unable to fin..
14.189265
= bluetoothd: profiles/audio/vcp.c:vcp_detached() Unable to find...
14.189498
= bluetoothd: Endpoint unregistered: sender=:1.163 path=/MediaEnd..
14.190532
= bluetoothd: Endpoint unregistered: sender=:1.163 path=/MediaEnd..
14.190598
= bluetoothd: Endpoint unregistered: sender=:1.163 path=/MediaEnd..
14.190653
= bluetoothd: Endpoint unregistered: sender=:1.163 path=/MediaEnd..
14.190714
= bluetoothd: profiles/audio/bap.c:bap_detached() Unable to find...
14.197087
= bluetoothd: Battery Provider Manager destroyed
14.197851
= New Index: 00:00:00:00:00:00 (Primary,USB,hci0) #0 [hci0]
14.436548
= Open Index: 00:00:00:00:00:00 [hci0]
14.436637
= Index Info: 00:00:00:00:00:00 (MediaTek, Inc.) [hci0]
14.436640
From the kernel/dmesg side:
[ 19.567508] mt7921e 0000:01:00.0: enabling device (0000 -> 0002)
[ 19.584454] mt7921e 0000:01:00.0: ASIC revision: 79220010
[ 19.662305] mt7921e 0000:01:00.0: HW/SW Version: 0x8a108a10, Build
Time: 20230627143702a
[ 20.048165] mt7921e 0000:01:00.0: WM Firmware Version: ____000000,
Build Time: 20230627143946
[ 21.137805] mt7921e 0000:01:00.0 wlp1s0: renamed from wlan0
[ 256.380280] Bluetooth: hci0: HW/SW Version: 0x008a008a, Build Time:
20230627144220
[ 256.521395] Bluetooth: hci0: Device setup in 144287 usecs
[ 256.521408] Bluetooth: hci0: HCI Enhanced Setup Synchronous
Connection command is advertised, but not supported.
[ 291.819885] Bluetooth: hci0: Opcode 0x2065 failed: -22
[ 293.109210] Bluetooth: hci0: HW/SW Version: 0x008a008a, Build Time:
20230627144220
[ 293.246331] Bluetooth: hci0: Device setup in 138096 usecs
[ 293.246342] Bluetooth: hci0: HCI Enhanced Setup Synchronous
Connection command is advertised, but not supported.
[ 315.219892] Bluetooth: hci0: Mediatek coredump end
[ 315.431355] usb 1-5: reset high-speed USB device number 3 using
xhci_hcd
[ 618.882312] bluetoothd[9227]: segfault at 55b685cb1f50 ip
00007f07b909d2ce sp 00007ffee7201e40 error 4 in
libc.so.6[7f07b9028000+15a000] likely on CPU 7 (core 3, socket 0)
[ 618.882350] Code: ff 66 66 2e 0f 1f 84 00 00 00 00 00 f3 0f 1e fa 48
85 ff 0f 84 bb 00 00 00 55 48 8d 77 f0 53 48 83 ec 18 48 8b 1d 42 db 13
00 <48> 8b 47 f8 64 8b 2b a8 02 75 57 48 8b 15 d0 da 13 00 64 48 83 3a
The adapter's firmware `HW/SW Version: 0x008a008a, Build Time:
20230627144220` is the latest in linux-firmware
(https://git.kernel.org/pub/scm/linux/kernel/git/firmware/linux-firmware.git/tree/WHENCE?id=ba1aa06f37477a702ebabf14b5753378460c9343#n5579)
Please let me know how best to proceed with this. Thanks!
Hi Tom,
On Thu, Nov 23, 2023 at 7:41 AM Tom Vincent <[email protected]> wrote:
>
> I'm working through an issue with Bluetooth LE Audio with a new headset
> (Creative Zen Hybrid Pro). The end result of which is no audio
> input/output device is created.
Hmm, didn't know that creative had this model with LE Audio, probably
will try to get one myself for testing.
> With the generous help from Pauli (cc'ed) of the pipewire team, we can
> see the headset connection establishes OK, but then appears to hit a
> bug in the adapter's firmware (MediaTek MT7922), which triggers a
> coredump and restarts.
>
> Full logs and other context can be found at
> https://gitlab.freedesktop.org/pipewire/pipewire/-/issues/3666#note_2179246
>
> ... but as an overview of logs via btmon:
>
> = Note: Linux version 6.6.1 (x86_64)
> 0.069372
> = Note: Bluetooth subsystem version 2.22
> 0.069374
> = New Index: 14:AC:60:46:9E:9C (Primary,USB,hci3) [hci3]
> 0.069375
> = Open Index: 14:AC:60:46:9E:9C [hci3]
> 0.069375
> = Index Info: 14:AC:60:46:9E:9C (MediaTek, Inc.) [hci3]
> 0.069376
> @ MGMT Open: bluetoothd (privileged) version 1.22 {0x0001}
> 0.069377
> @ MGMT Open: bluetoothctl version 1.22 {0x0002}
> 0.069378
>
> > ACL Data RX: Handle 512 flags 0x01 dlen 24 #142 [hci3]
> 12.183718
> ATT: Handle Value Notification (0x1b) len 46
> < HCI Command: LE Set Connected... (0x08|0x0062) plen 24 #143 [hci3]
> 12.183932
> CIG ID: 0x00
> > HCI Event: Command Complete (0x0e) plen 8 #144 [hci3]
> 12.184357
> LE Set Connected Isochronous Group Parameters (0x08|0x0062) ncmd
> 1
>
> [ ... snip ... ]
>
> > HCI Event: Command Status (0x0f) plen 4 #170 [hci3]
> 12.664283
> LE Create Connected Isochronous Stream (0x08|0x0064) ncmd 1
>
> [ ... snip ... ]
>
> > HCI Event: LE Meta Event (0x3e) plen 29 #186 [hci3]
> 13.203205
> LE Connected Isochronous Stream Established (0x19)
> < HCI Command: LE Setup Isochron.. (0x08|0x006e) plen 13 #187 [hci3]
> 13.203326
> Handle: 1024
> @ RAW Open: btmon (privileged) version 2.22 {0x0003}
> 13.203504
> @ RAW Close: btmon {0x0003}
> 13.203512
> > HCI Event: Command Complete (0x0e) plen 6 #188 [hci3]
> 13.203576
> LE Setup Isochronous Data Path (0x08|0x006e) ncmd 1
> < HCI Command: LE Setup Isochron.. (0x08|0x006e) plen 13 #189 [hci3]
> 13.203606
> Handle: 1024
> > HCI Event: Command Complete (0x0e) plen 6 #190 [hci3]
> 13.203929
> LE Setup Isochronous Data Path (0x08|0x006e) ncmd 1
> < ACL Data TX: Handle 512 flags 0x00 dlen 10 #191 [hci3]
> 13.214209
> ATT: Write Command (0x52) len 5
> = Vendor Diagnostic (len 72) [hci3]
> 13.323162
> 6f fc 44 00 3c 41 53 53 45 52 54 3e 20 2e 2e 2f o.D.<ASSERT>
> ../
>
> [ ... snip ... ]
>
> = Vendor Diagnostic (len 19) [hci3]
> 14.095706
> 6f fc 0f 00 0a 3b 63 6f 72 65 64 75 6d 70 20 65 o....;coredump
> e
>
> [ ... snip ... ]
> = bluetoothd: profiles/audio/transport.c:link_set_state() Unable...
> 14.143326
> = Close Index: 14:AC:60:46:9E:9C [hci3]
> 14.188719
> @ MGMT Event: Index Removed (0x0005) plen 0 {0x0001} [hci3]
> 14.188726
> @ MGMT Event: Index Removed (0x0005) plen 0 {0x0002} [hci3]
> 14.188726
> = Delete Index: 14:AC:60:46:9E:9C [hci3]
> 14.188733
> = bluetoothd: profiles/audio/micp.c:micp_detached() unable to fin..
> 14.189265
> = bluetoothd: profiles/audio/vcp.c:vcp_detached() Unable to find...
> 14.189498
> = bluetoothd: Endpoint unregistered: sender=:1.163 path=/MediaEnd..
> 14.190532
> = bluetoothd: Endpoint unregistered: sender=:1.163 path=/MediaEnd..
> 14.190598
> = bluetoothd: Endpoint unregistered: sender=:1.163 path=/MediaEnd..
> 14.190653
> = bluetoothd: Endpoint unregistered: sender=:1.163 path=/MediaEnd..
> 14.190714
> = bluetoothd: profiles/audio/bap.c:bap_detached() Unable to find...
> 14.197087
> = bluetoothd: Battery Provider Manager destroyed
> 14.197851
> = New Index: 00:00:00:00:00:00 (Primary,USB,hci0) #0 [hci0]
> 14.436548
> = Open Index: 00:00:00:00:00:00 [hci0]
> 14.436637
> = Index Info: 00:00:00:00:00:00 (MediaTek, Inc.) [hci0]
> 14.436640
>
> From the kernel/dmesg side:
>
> [ 19.567508] mt7921e 0000:01:00.0: enabling device (0000 -> 0002)
> [ 19.584454] mt7921e 0000:01:00.0: ASIC revision: 79220010
> [ 19.662305] mt7921e 0000:01:00.0: HW/SW Version: 0x8a108a10, Build
> Time: 20230627143702a
> [ 20.048165] mt7921e 0000:01:00.0: WM Firmware Version: ____000000,
> Build Time: 20230627143946
> [ 21.137805] mt7921e 0000:01:00.0 wlp1s0: renamed from wlan0
> [ 256.380280] Bluetooth: hci0: HW/SW Version: 0x008a008a, Build Time:
> 20230627144220
> [ 256.521395] Bluetooth: hci0: Device setup in 144287 usecs
> [ 256.521408] Bluetooth: hci0: HCI Enhanced Setup Synchronous
> Connection command is advertised, but not supported.
> [ 291.819885] Bluetooth: hci0: Opcode 0x2065 failed: -22
> [ 293.109210] Bluetooth: hci0: HW/SW Version: 0x008a008a, Build Time:
> 20230627144220
> [ 293.246331] Bluetooth: hci0: Device setup in 138096 usecs
> [ 293.246342] Bluetooth: hci0: HCI Enhanced Setup Synchronous
> Connection command is advertised, but not supported.
> [ 315.219892] Bluetooth: hci0: Mediatek coredump end
> [ 315.431355] usb 1-5: reset high-speed USB device number 3 using
> xhci_hcd
> [ 618.882312] bluetoothd[9227]: segfault at 55b685cb1f50 ip
> 00007f07b909d2ce sp 00007ffee7201e40 error 4 in
> libc.so.6[7f07b9028000+15a000] likely on CPU 7 (core 3, socket 0)
> [ 618.882350] Code: ff 66 66 2e 0f 1f 84 00 00 00 00 00 f3 0f 1e fa 48
> 85 ff 0f 84 bb 00 00 00 55 48 8d 77 f0 53 48 83 ec 18 48 8b 1d 42 db 13
> 00 <48> 8b 47 f8 64 8b 2b a8 02 75 57 48 8b 15 d0 da 13 00 64 48 83 3a
>
>
> The adapter's firmware `HW/SW Version: 0x008a008a, Build Time:
> 20230627144220` is the latest in linux-firmware
> (https://git.kernel.org/pub/scm/linux/kernel/git/firmware/linux-firmware.git/tree/WHENCE?id=ba1aa06f37477a702ebabf14b5753378460c9343#n5579)
>
> Please let me know how best to proceed with this. Thanks!
You will probably need to reach out to the manufacturer in this case
since it seems something is happening below HCI which we don't really
have any control over, the other option is to switch to another
controller capable of creating Isochronous Channels.
--
Luiz Augusto von Dentz
Hey Luiz,
I've heard back from the MediaTek maintainers listed in linux-firmware
and they confirmed this adapter doesn't LE Audio yet, but they're
working on it
> While MediaTek has made progress in LE audio on the MT7921/7922
> Windows platform, we have not yet officially declared support for LE
> audio on Linux-based systems with BlueZ.
I'll try a AX210 in the meantime.
Thanks!