[HFP] RFCOMM Server not binding HFP-spoofed USB Dongle to iPhone on BT Connect

I'm having an issue with the `bumble/examples/run_hfp_handsfree.py` example script. From my understanding, this script should allow a USB Dongle (I'm using a BT820/CSR8510 A10) to be spoofed as a HFP headset.

Here is the command I used, along with its traceback:

```{bash}
PS C:\Users\KevinZhao\Downloads\VSCode\bumble\examples> python3 run_hfp_handsfree.py classic2.json usb:0a12:0001
DEBUG:asyncio:Using proactor: IocpProactor
<<< connecting to HCI...
DEBUG:bumble.transport.usb:USB Device: Bus 001 Device 001: ID 0a12:0001
DEBUG:bumble.transport.usb:selected endpoints: configuration=1, interface=0, setting=0, acl_in=0x82, acl_out=0x02, events_in=0x81,
DEBUG:bumble.transport.usb:current configuration = 1
DEBUG:bumble.transport.usb:starting USB event loop
<<< connected
### Listening for connection on channel 1
DEBUG:bumble.drivers:Probing driver class: rtk
DEBUG:bumble.drivers.rtk:USB device (0A12, 0001) not in known list
DEBUG:bumble.drivers:Probing driver class: intel
DEBUG:bumble.drivers.intel:USB device (0A12, 0001) not in known list
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_RESET_COMMAND
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_RESET_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_READ_LOCAL_SUPPORTED_COMMANDS_COMMAND
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_READ_LOCAL_SUPPORTED_COMMANDS_COMMAND
return_parameters:
status: HCI_SUCCESS
supported_commands: ffffff03fefffffffffffffff30fe8fe3ff783ff1c00000061f7ffff7f0000000000000000000000000000000000000000000000000000000000000000000000
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_LE_READ_LOCAL_SUPPORTED_FEATURES_COMMAND
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_LE_READ_LOCAL_SUPPORTED_FEATURES_COMMAND
return_parameters:
status: HCI_SUCCESS
le_features: 0100000000000000
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_READ_LOCAL_VERSION_INFORMATION_COMMAND
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_READ_LOCAL_VERSION_INFORMATION_COMMAND
return_parameters:
status: HCI_SUCCESS
hci_version: 6
hci_subversion: 8891
lmp_version: 6
company_identifier: 10
lmp_subversion: 8891
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_READ_LOCAL_EXTENDED_FEATURES_COMMAND:
page_number: 0
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_READ_LOCAL_EXTENDED_FEATURES_COMMAND
return_parameters:
status: HCI_SUCCESS
page_number: 0
maximum_page_number: 0
extended_lmp_features: ffff8ffedbff5b87
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_SET_EVENT_MASK_COMMAND:
event_mask: ff9ffbbf07f8bf3d
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_SET_EVENT_MASK_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_LE_SET_EVENT_MASK_COMMAND:
le_event_mask: 1f00000000000000
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_LE_SET_EVENT_MASK_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_READ_BUFFER_SIZE_COMMAND
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_READ_BUFFER_SIZE_COMMAND
return_parameters:
status: HCI_SUCCESS
hc_acl_data_packet_length: 310
hc_synchronous_data_packet_length: 64
hc_total_num_acl_data_packets: 10
hc_total_num_synchronous_data_packets: 8
DEBUG:bumble.host:HCI ACL flow control: hc_acl_data_packet_length=310,hc_total_num_acl_data_packets=10
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_LE_READ_BUFFER_SIZE_COMMAND
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_LE_READ_BUFFER_SIZE_COMMAND
return_parameters:
status: HCI_SUCCESS
le_acl_data_packet_length: 0
total_num_le_acl_data_packets: 0
DEBUG:bumble.host:HCI LE ACL flow control: le_acl_data_packet_length=0,total_num_le_acl_data_packets=0
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_READ_BD_ADDR_COMMAND
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_READ_BD_ADDR_COMMAND
return_parameters:
status: HCI_SUCCESS
bd_addr: 00:16:A4:6C:9B:50/P
DEBUG:bumble.device:BD_ADDR: 00:16:A4:6C:9B:50/P
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_LE_HOST_SUPPORT_COMMAND:
le_supported_host: 1
simultaneous_le_host: 0
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_WRITE_LE_HOST_SUPPORT_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.device:LE Random Address: FF:42:F3:20:E3:B9
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_LE_SET_RANDOM_ADDRESS_COMMAND:
random_address: FF:42:F3:20:E3:B9
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_LE_SET_RANDOM_ADDRESS_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_LOCAL_NAME_COMMAND:
local_name: Bumble USB Dongle
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_WRITE_LOCAL_NAME_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_CLASS_OF_DEVICE_COMMAND:
class_of_device: [200408] Services(Audio),Class(Audio/Video|Hands-free Device)
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_WRITE_CLASS_OF_DEVICE_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_SIMPLE_PAIRING_MODE_COMMAND:
simple_pairing_mode: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_WRITE_SIMPLE_PAIRING_MODE_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_SECURE_CONNECTIONS_HOST_SUPPORT_COMMAND:
secure_connections_host_support: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_STATUS_EVENT:
status: HCI_UNKNOWN_HCI_COMMAND_ERROR
num_hci_command_packets: 1
command_opcode: HCI_WRITE_SECURE_CONNECTIONS_HOST_SUPPORT_COMMAND
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_SCAN_ENABLE_COMMAND:
scan_enable: 3
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_WRITE_SCAN_ENABLE_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_EXTENDED_INQUIRY_RESPONSE_COMMAND:
fec_required: 0
extended_inquiry_response: 120942756d626c652055534220446f6e676c65
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_WRITE_EXTENDED_INQUIRY_RESPONSE_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_SCAN_ENABLE_COMMAND:
scan_enable: 3
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_WRITE_SCAN_ENABLE_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_PAGE_SCAN_TYPE_COMMAND:
page_scan_type: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_WRITE_PAGE_SCAN_TYPE_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_INQUIRY_SCAN_TYPE_COMMAND:
scan_type: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_WRITE_INQUIRY_SCAN_TYPE_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_EXTENDED_INQUIRY_RESPONSE_COMMAND:
fec_required: 0
extended_inquiry_response: 120942756d626c652055534220446f6e676c65
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_WRITE_EXTENDED_INQUIRY_RESPONSE_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_SCAN_ENABLE_COMMAND:
scan_enable: 3
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_WRITE_SCAN_ENABLE_COMMAND
return_parameters: HCI_SUCCESS
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_WRITE_SCAN_ENABLE_COMMAND:
scan_enable: 3
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_WRITE_SCAN_ENABLE_COMMAND
return_parameters: HCI_SUCCESS
INFO:websockets.server:server listening on 127.0.0.1:8989
INFO:websockets.server:server listening on [::1]:8989
```
^ By this point, the BT connection is powered on and discoverable, and I can see it on my phone. Clicking the BT icon yields the following:

```{bash}
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_CONNECTION_REQUEST_EVENT:
bd_addr: 88:B9:45:3A:01:13/P
class_of_device: 7995916
link_type: ACL
DEBUG:bumble.device:*** Connection request: 88:B9:45:3A:01:13/P
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_ACCEPT_CONNECTION_REQUEST_COMMAND:
bd_addr: 88:B9:45:3A:01:13/P
role: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_STATUS_EVENT:
status: PENDING
num_hci_command_packets: 1
command_opcode: HCI_ACCEPT_CONNECTION_REQUEST_COMMAND
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_CONNECTION_COMPLETE_EVENT:
status: HCI_SUCCESS
connection_handle: 72
bd_addr: 88:B9:45:3A:01:13/P
link_type: ACL
encryption_enabled: 0
DEBUG:bumble.host:### BR/EDR CONNECTION: [0x0048] 88:B9:45:3A:01:13/P
DEBUG:bumble.device:*** Connection: [0x0048] 88:B9:45:3A:01:13/P
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_PAGE_SCAN_REPETITION_MODE_CHANGE_EVENT:
bd_addr: 88:B9:45:3A:01:13/P
page_scan_repetition_mode: 0
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_MAX_SLOTS_CHANGE_EVENT:
connection_handle: 72
lmp_max_slots: 5
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=10, data=060001000a0e02000200
DEBUG:bumble.hci:<<< ACL PDU: 060001000a0e02000200
DEBUG:bumble.l2cap:<<< Received L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_INFORMATION_REQUEST [ID=14]:
info_type: EXTENDED_FEATURES_SUPPORTED
DEBUG:bumble.l2cap:>>> Sending L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_INFORMATION_RESPONSE [ID=14]:
info_type: EXTENDED_FEATURES_SUPPORTED
result: SUCCESS
data: 80000000
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=1) ACL: handle=0x0048, pb=0, bc=0, data_total_length=16, data=0c0001000b0e08000200000080000000
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=16, data=0c0001000b0e08000200000080000000
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=10, data=060001000a0f02000300
DEBUG:bumble.hci:<<< ACL PDU: 060001000a0f02000300
DEBUG:bumble.l2cap:<<< Received L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_INFORMATION_REQUEST [ID=15]:
info_type: FIXED_CHANNELS_SUPPORTED
DEBUG:bumble.l2cap:>>> Sending L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_INFORMATION_RESPONSE [ID=15]:
info_type: FIXED_CHANNELS_SUPPORTED
result: SUCCESS
data: f200000000000000
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=1) ACL: handle=0x0048, pb=0, bc=0, data_total_length=20, data=100001000b0f0c0003000000f200000000000000
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=20, data=100001000b0f0c0003000000f200000000000000
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=12, data=080001000210040001000819
DEBUG:bumble.hci:<<< ACL PDU: 080001000210040001000819
DEBUG:bumble.l2cap:<<< Received L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_CONNECTION_REQUEST [ID=16]:
psm: 1
source_cid: 6408
DEBUG:bumble.l2cap:creating server channel with cid=64 for psm 1
DEBUG:bumble.l2cap:Channel(64->6408, PSM=1, MTU=2048/48, state=CLOSED) state change -> WAIT_CONNECT
DEBUG:bumble.l2cap:>>> Sending L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_CONNECTION_RESPONSE [ID=16]:
destination_cid: 64
source_cid: 6408
result: CONNECTION_SUCCESSFUL
status: 0
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=1) ACL: handle=0x0048, pb=0, bc=0, data_total_length=16, data=0c000100031008004000081900000000
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=16, data=0c000100031008004000081900000000
DEBUG:bumble.l2cap:Channel(64->6408, PSM=1, MTU=2048/48, state=WAIT_CONNECT) state change -> WAIT_CONFIG
DEBUG:bumble.l2cap:>>> Sending L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_CONFIGURE_REQUEST [ID=1]:
destination_cid: 6408
flags: 0
options: 01020008
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=1) ACL: handle=0x0048, pb=0, bc=0, data_total_length=16, data=0c000100040108000819000001020008
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=16, data=0c000100040108000819000001020008
DEBUG:bumble.l2cap:Channel(64->6408, PSM=1, MTU=2048/48, state=WAIT_CONFIG) state change -> WAIT_CONFIG_REQ_RSP
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_LINK_SUPERVISION_TIMEOUT_CHANGED_EVENT:
connection_handle: 72
link_supervision_timeout: 8000
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=16, data=0c000100041108004000000001020001
DEBUG:bumble.hci:<<< ACL PDU: 0c000100041108004000000001020001
DEBUG:bumble.l2cap:<<< Received L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_CONFIGURE_REQUEST [ID=17]:
destination_cid: 64
flags: 0
options: 01020001
DEBUG:bumble.l2cap:peer MTU = 256
DEBUG:bumble.l2cap:>>> Sending L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_CONFIGURE_RESPONSE [ID=17]:
source_cid: 6408
flags: 0
result: SUCCESS
options: 01020001
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=1) ACL: handle=0x0048, pb=0, bc=0, data_total_length=18, data=0e00010005110a0008190000000001020001
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=18, data=0e00010005110a0008190000000001020001
DEBUG:bumble.l2cap:Channel(64->6408, PSM=1, MTU=2048/256, state=WAIT_CONFIG_REQ_RSP) state change -> WAIT_CONFIG_RSP
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=18, data=0e00010005010a0040000000000001020008
DEBUG:bumble.hci:<<< ACL PDU: 0e00010005010a0040000000000001020008
DEBUG:bumble.l2cap:<<< Received L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_CONFIGURE_RESPONSE [ID=1]:
source_cid: 64
flags: 0
result: SUCCESS
options: 01020008
DEBUG:bumble.l2cap:Channel(64->6408, PSM=1, MTU=2048/256, state=WAIT_CONFIG_RSP) state change -> OPEN
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=17, data=0d00400002000100083503190100003000
DEBUG:bumble.hci:<<< ACL PDU: 0d00400002000100083503190100003000
DEBUG:bumble.sdp:<<< Received SDP Request: SDP_SERVICE_SEARCH_REQUEST [TID=1]:
service_search_pattern: SEQUENCE([UUID(UUID-16:0100 (L2CAP))])
maximum_service_record_count: 48
continuation_state: 00
DEBUG:bumble.sdp:Service Record Handles: [65537]
DEBUG:bumble.sdp:>>> Sending SDP Response: SDP_SERVICE_SEARCH_RESPONSE [TID=1]:
total_service_record_count: 1
current_service_record_count: 1
service_record_handle_list: 00010001
continuation_state: 00
DEBUG:bumble.l2cap:>>> Sending L2CAP PDU on connection [0x0048] (CID=6408) 88:B9:45:3A:01:13/P: 14 bytes, SDP_SERVICE_SEARCH_RESPONSE [TID=1]:
total_service_record_count: 1
current_service_record_count: 1
service_record_handle_list: 00010001
continuation_state: 00
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=6408) ACL: handle=0x0048, pb=0, bc=0, data_total_length=18, data=0e0008190300010009000100010001000100
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=18, data=0e0008190300010009000100010001000100
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=23, data=13004000040002000e00010001010035050a0000ffff00
DEBUG:bumble.hci:<<< ACL PDU: 13004000040002000e00010001010035050a0000ffff00
DEBUG:bumble.sdp:<<< Received SDP Request: SDP_SERVICE_ATTRIBUTE_REQUEST [TID=2]:
service_record_handle: 65537
maximum_attribute_byte_count: 256
attribute_id_list: SEQUENCE([UNSIGNED_INTEGER(65535#4)])
continuation_state: 00
DEBUG:bumble.sdp:Attributes: SEQUENCE([UNSIGNED_INTEGER(0#2),UNSIGNED_INTEGER(65537#4),UNSIGNED_INTEGER(1#2),SEQUENCE([UUID(UUID-16:111E (Handsfree)),UUID(UUID-16:1203 (GenericAudio))]),UNSIGNED_INTEGER(4#2),SEQUENCE([SEQUENCE([UUID(UUID-16:0100 (L2CAP))]),SEQUENCE([UUID(UUID-16:0003 (RFCOMM)),UNSIGNED_INTEGER(1#1)])]),UNSIGNED_INTEGER(9#2),SEQUENCE([SEQUENCE([UUID(UUID-16:111E (Handsfree)),UNSIGNED_INTEGER(264#2)])]),UNSIGNED_INTEGER(785#2),UNSIGNED_INTEGER(50#2)])
DEBUG:bumble.sdp:>>> Sending SDP Response: SDP_SERVICE_ATTRIBUTE_RESPONSE [TID=2]:
attribute_list_byte_count: 57
attribute_list: 35370900000a00010001090001350619111e191203090004350c3503190100350519000308010900093508350619111e090108090311090032
continuation_state: 00
DEBUG:bumble.l2cap:>>> Sending L2CAP PDU on connection [0x0048] (CID=6408) 88:B9:45:3A:01:13/P: 65 bytes, SDP_SERVICE_ATTRIBUTE_RESPONSE [TID=2]:
attribute_list_byte_count: 57
attribute_list: 35370900000a00010001090001350619111e191203090004350c3503190100350519000308010900093508350619111e090108090311090032
continuation_state: 00
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=6408) ACL: handle=0x0048, pb=0, bc=0, data_total_length=69, data=41000819050002003c003935370900000a00010001090001350619111e191203090004350c3503190100350519000308010900093508350619111e09010809031109003200
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=69, data=41000819050002003c003935370900000a00010001090001350619111e191203090004350c3503190100350519000308010900093508350619111e09010809031109003200
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=17, data=0d00400002000300083503191200003000
DEBUG:bumble.hci:<<< ACL PDU: 0d00400002000300083503191200003000
DEBUG:bumble.sdp:<<< Received SDP Request: SDP_SERVICE_SEARCH_REQUEST [TID=3]:
service_search_pattern: SEQUENCE([UUID(UUID-16:1200 (PnPInformation))])
maximum_service_record_count: 48
continuation_state: 00
DEBUG:bumble.sdp:Service Record Handles: []
DEBUG:bumble.sdp:>>> Sending SDP Response: SDP_SERVICE_SEARCH_RESPONSE [TID=3]:
total_service_record_count: 0
current_service_record_count: 0
service_record_handle_list:
continuation_state: 00
DEBUG:bumble.l2cap:>>> Sending L2CAP PDU on connection [0x0048] (CID=6408) 88:B9:45:3A:01:13/P: 10 bytes, SDP_SERVICE_SEARCH_RESPONSE [TID=3]:
total_service_record_count: 0
current_service_record_count: 0
service_record_handle_list:
continuation_state: 00
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=6408) ACL: handle=0x0048, pb=0, bc=0, data_total_length=14, data=0a00081903000300050000000000
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=14, data=0a00081903000300050000000000
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=12, data=080001000612040040000819
DEBUG:bumble.hci:<<< ACL PDU: 080001000612040040000819
DEBUG:bumble.l2cap:<<< Received L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_DISCONNECTION_REQUEST [ID=18]:
destination_cid: 64
source_cid: 6408
DEBUG:bumble.l2cap:>>> Sending L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_DISCONNECTION_RESPONSE [ID=18]:
destination_cid: 64
source_cid: 6408
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=1) ACL: handle=0x0048, pb=0, bc=0, data_total_length=12, data=080001000712040040000819
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=12, data=080001000712040040000819
DEBUG:bumble.l2cap:Channel(64->6408, PSM=1, MTU=2048/256, state=OPEN) state change -> CLOSED
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_LINK_SUPERVISION_TIMEOUT_CHANGED_EVENT:
connection_handle: 72
link_supervision_timeout: 32000
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_IO_CAPABILITY_RESPONSE_EVENT:
bd_addr: 88:B9:45:3A:01:13/P
io_capability: HCI_DISPLAY_YES_NO_IO_CAPABILITY
oob_data_present: 0
authentication_requirements: HCI_MITM_NOT_REQUIRED_GENERAL_BONDING_AUTHENTICATION_REQUIREMENTS
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_IO_CAPABILITY_REQUEST_EVENT:
bd_addr: 88:B9:45:3A:01:13/P
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_IO_CAPABILITY_REQUEST_REPLY_COMMAND:
bd_addr: 88:B9:45:3A:01:13/P
io_capability: HCI_NO_INPUT_NO_OUTPUT_IO_CAPABILITY
oob_data_present: 0
authentication_requirements: HCI_MITM_REQUIRED_GENERAL_BONDING_AUTHENTICATION_REQUIREMENTS
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_IO_CAPABILITY_REQUEST_REPLY_COMMAND
return_parameters:
status: HCI_SUCCESS
bd_addr: 88:B9:45:3A:01:13/P
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_USER_CONFIRMATION_REQUEST_EVENT:
bd_addr: 88:B9:45:3A:01:13/P
numeric_value: 366758
DEBUG:bumble.host:### HOST -> CONTROLLER: HCI_USER_CONFIRMATION_REQUEST_REPLY_COMMAND:
bd_addr: 88:B9:45:3A:01:13/P
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 1
command_opcode: HCI_USER_CONFIRMATION_REQUEST_REPLY_COMMAND
return_parameters:
status: HCI_SUCCESS
bd_addr: 88:B9:45:3A:01:13/P
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_SIMPLE_PAIRING_COMPLETE_EVENT:
status: HCI_SUCCESS
bd_addr: 88:B9:45:3A:01:13/P
DEBUG:bumble.host:simple pairing complete for 88:B9:45:3A:01:13/P: status=HCI_SUCCESS
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_LINK_KEY_NOTIFICATION_EVENT:
bd_addr: 88:B9:45:3A:01:13/P
link_key: b692a2de7b5711c8c6233cc29e521e6c
key_type: HCI_UNAUTHENTICATED_COMBINATION_KEY_GENERATED_FROM_P_192_TYPE
DEBUG:bumble.host:link key for 88:B9:45:3A:01:13/P: b692a2de7b5711c8c6233cc29e521e6c, type=HCI_UNAUTHENTICATED_COMBINATION_KEY_GENERATED_FROM_P_192_TYPE
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_ENCRYPTION_CHANGE_EVENT:
status: HCI_SUCCESS
connection_handle: 72
encryption_enabled: E0_OR_AES_CCM
DEBUG:bumble.device:*** Connection Encryption Change: [0x0048] 88:B9:45:3A:01:13/P as PERIPHERAL, encryption=1
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=12, data=08000100021304000300081a
DEBUG:bumble.hci:<<< ACL PDU: 08000100021304000300081a
DEBUG:bumble.l2cap:<<< Received L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_CONNECTION_REQUEST [ID=19]:
psm: 3
source_cid: 6664
DEBUG:bumble.l2cap:creating server channel with cid=64 for psm 3
DEBUG:bumble.rfcomm:+++ new L2CAP connection: Channel(64->0, PSM=3, MTU=2048/48, state=CLOSED)
DEBUG:bumble.l2cap:Channel(64->6664, PSM=3, MTU=2048/48, state=CLOSED) state change -> WAIT_CONNECT
DEBUG:bumble.l2cap:>>> Sending L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_CONNECTION_RESPONSE [ID=19]:
destination_cid: 64
source_cid: 6664
result: CONNECTION_SUCCESSFUL
status: 0
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=1) ACL: handle=0x0048, pb=0, bc=0, data_total_length=16, data=0c000100031308004000081a00000000
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=16, data=0c000100031308004000081a00000000
DEBUG:bumble.l2cap:Channel(64->6664, PSM=3, MTU=2048/48, state=WAIT_CONNECT) state change -> WAIT_CONFIG
DEBUG:bumble.l2cap:>>> Sending L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_CONFIGURE_REQUEST [ID=2]:
destination_cid: 6664
flags: 0
options: 01020008
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=1) ACL: handle=0x0048, pb=0, bc=0, data_total_length=16, data=0c00010004020800081a000001020008
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=16, data=0c00010004020800081a000001020008
DEBUG:bumble.l2cap:Channel(64->6664, PSM=3, MTU=2048/48, state=WAIT_CONFIG) state change -> WAIT_CONFIG_REQ_RSP
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_LINK_SUPERVISION_TIMEOUT_CHANGED_EVENT:
connection_handle: 72
link_supervision_timeout: 8000
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=12, data=080001000414040040000000
DEBUG:bumble.hci:<<< ACL PDU: 080001000414040040000000
DEBUG:bumble.l2cap:<<< Received L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_CONFIGURE_REQUEST [ID=20]:
destination_cid: 64
flags: 0
options:
DEBUG:bumble.l2cap:>>> Sending L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_CONFIGURE_RESPONSE [ID=20]:
source_cid: 6664
flags: 0
result: SUCCESS
options:
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=1) ACL: handle=0x0048, pb=0, bc=0, data_total_length=14, data=0a00010005140600081a00000000
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=14, data=0a00010005140600081a00000000
DEBUG:bumble.l2cap:Channel(64->6664, PSM=3, MTU=2048/48, state=WAIT_CONFIG_REQ_RSP) state change -> WAIT_CONFIG_RSP
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=18, data=0e00010005020a0040000000000001020008
DEBUG:bumble.hci:<<< ACL PDU: 0e00010005020a0040000000000001020008
DEBUG:bumble.l2cap:<<< Received L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_CONFIGURE_RESPONSE [ID=2]:
source_cid: 64
flags: 0
result: SUCCESS
options: 01020008
DEBUG:bumble.l2cap:Channel(64->6664, PSM=3, MTU=2048/48, state=WAIT_CONFIG_RSP) state change -> OPEN
DEBUG:bumble.rfcomm:$$$ L2CAP channel open: Channel(64->6664, PSM=3, MTU=2048/48, state=OPEN)
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=8, data=04004000033f011c
DEBUG:bumble.hci:<<< ACL PDU: 04004000033f011c
DEBUG:bumble.rfcomm:<<< Multiplexer received SABM(c/r=1,dlci=0,p/f=1,length=0,fcs=0x1C)
DEBUG:bumble.rfcomm:Multiplexer(state=INIT) state change -> CONNECTED
DEBUG:bumble.rfcomm:>>> Multiplexer sending UA(c/r=1,dlci=0,p/f=1,length=0,fcs=0xD7)
DEBUG:bumble.l2cap:>>> Sending L2CAP PDU on connection [0x0048] (CID=6664) 88:B9:45:3A:01:13/P: 4 bytes, UA(c/r=1,dlci=0,p/f=1,length=0,fcs=0xD7)
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=6664) ACL: handle=0x0048, pb=0, bc=0, data_total_length=8, data=0400081a037301d7
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=8, data=0400081a037301d7
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=18, data=0e00400003ef15831102f00000fa03000070
DEBUG:bumble.hci:<<< ACL PDU: 0e00400003ef15831102f00000fa03000070
DEBUG:bumble.rfcomm:<<< Multiplexer received UIH(c/r=1,dlci=0,p/f=0,length=10,fcs=0x70)
WARNING:bumble.rfcomm:Initial credits 0 is out of range [1, 7].
DEBUG:bumble.rfcomm:<<< PN Command: RFCOMM_MCC_PN(dlci=2, cl=240, priority=0, ack_timer=0, max_frame_size=1018, max_retransmissions=0, initial_credits=0)
DEBUG:bumble.rfcomm:>>> PN Response: RFCOMM_MCC_PN(dlci=2, cl=224, priority=7, ack_timer=0, max_frame_size=2000, max_retransmissions=0, initial_credits=7)
DEBUG:bumble.rfcomm:>>> Multiplexer sending UIH(c/r=0,dlci=0,p/f=0,length=10,fcs=0xAA)
DEBUG:bumble.l2cap:>>> Sending L2CAP PDU on connection [0x0048] (CID=6664) 88:B9:45:3A:01:13/P: 14 bytes, UIH(c/r=0,dlci=0,p/f=0,length=10,fcs=0xAA)
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=6664) ACL: handle=0x0048, pb=0, bc=0, data_total_length=18, data=0e00081a01ef15811102e00700d0070007aa
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=18, data=0e00081a01ef15811102e00700d0070007aa
DEBUG:bumble.rfcomm:DLC(dlci=2, state=INIT, rx_max_frame_size=2000, rx_credits=7, rx_max_credits=32, tx_max_frame_size=1018, tx_credits=0) state change -> CONNECTING
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=8, data=04004000035301fd
DEBUG:bumble.hci:<<< ACL PDU: 04004000035301fd
DEBUG:bumble.rfcomm:<<< Multiplexer received DISC(c/r=1,dlci=0,p/f=1,length=0,fcs=0xFD)
DEBUG:bumble.rfcomm:Multiplexer(state=CONNECTED) state change -> DISCONNECTED
DEBUG:bumble.rfcomm:>>> Multiplexer sending UA(c/r=1,dlci=0,p/f=1,length=0,fcs=0xD7)
DEBUG:bumble.l2cap:>>> Sending L2CAP PDU on connection [0x0048] (CID=6664) 88:B9:45:3A:01:13/P: 4 bytes, UA(c/r=1,dlci=0,p/f=1,length=0,fcs=0xD7)
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=6664) ACL: handle=0x0048, pb=0, bc=0, data_total_length=8, data=0400081a037301d7
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=8, data=0400081a037301d7
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=8, data=040040000b3f0159
DEBUG:bumble.hci:<<< ACL PDU: 040040000b3f0159
DEBUG:bumble.rfcomm:<<< Multiplexer received SABM(c/r=1,dlci=2,p/f=1,length=0,fcs=0x59)
DEBUG:bumble.rfcomm:>>> Multiplexer sending UA(c/r=1,dlci=2,p/f=1,length=0,fcs=0x92)
DEBUG:bumble.l2cap:>>> Sending L2CAP PDU on connection [0x0048] (CID=6664) 88:B9:45:3A:01:13/P: 4 bytes, UA(c/r=1,dlci=2,p/f=1,length=0,fcs=0x92)
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=6664) ACL: handle=0x0048, pb=0, bc=0, data_total_length=8, data=0400081a0b730192
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=8, data=0400081a0b730192
DEBUG:bumble.rfcomm:>>> MCC MSC Command: RFCOMM_MCC_MSC(dlci=2, fc=0, rtc=1, rtr=1, ic=0, dv=1)
DEBUG:bumble.rfcomm:>>> Multiplexer sending UIH(c/r=0,dlci=0,p/f=0,length=4,fcs=0xAA)
DEBUG:bumble.l2cap:>>> Sending L2CAP PDU on connection [0x0048] (CID=6664) 88:B9:45:3A:01:13/P: 8 bytes, UIH(c/r=0,dlci=0,p/f=0,length=4,fcs=0xAA)
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=6664) ACL: handle=0x0048, pb=0, bc=0, data_total_length=12, data=0800081a01ef09e3050b8daa
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=12, data=0800081a01ef09e3050b8daa
DEBUG:bumble.rfcomm:DLC(dlci=2, state=CONNECTING, rx_max_frame_size=2000, rx_credits=7, rx_max_credits=32, tx_max_frame_size=1018, tx_credits=0) state change -> CONNECTED
DEBUG:bumble.rfcomm:@@@ new DLC connected: DLC(dlci=2, state=CONNECTED, rx_max_frame_size=2000, rx_credits=7, rx_max_credits=32, tx_max_frame_size=1018, tx_credits=0)
*** DLC connected DLC(dlci=2, state=CONNECTED, rx_max_frame_size=2000, rx_credits=7, rx_max_credits=32, tx_max_frame_size=1018, tx_credits=0)
DEBUG:bumble.hfp:>>> AT+BRSF=1010
DEBUG:bumble.rfcomm:>>> sending 14 bytes with 25 credits, rx_credits=32, tx_credits=-1
DEBUG:bumble.rfcomm:>>> Multiplexer sending UIH(c/r=0,dlci=2,p/f=1,length=14,fcs=0x5C)
DEBUG:bumble.l2cap:>>> Sending L2CAP PDU on connection [0x0048] (CID=6664) 88:B9:45:3A:01:13/P: 18 bytes, UIH(c/r=0,dlci=2,p/f=1,length=14,fcs=0x5C)
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=6664) ACL: handle=0x0048, pb=0, bc=0, data_total_length=22, data=1200081a09ff1b1941542b425253463d313031300d5c
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=22, data=1200081a09ff1b1941542b425253463d313031300d5c
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: ACL: handle=0x0048, pb=2, bc=0, data_total_length=12, data=08000100061504004000081a
DEBUG:bumble.hci:<<< ACL PDU: 08000100061504004000081a
DEBUG:bumble.l2cap:<<< Received L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_DISCONNECTION_REQUEST [ID=21]:
destination_cid: 64
source_cid: 6664
DEBUG:bumble.l2cap:>>> Sending L2CAP Signaling Control Frame on connection [0x0048] (CID=1) 88:B9:45:3A:01:13/P:
L2CAP_DISCONNECTION_RESPONSE [ID=21]:
destination_cid: 64
source_cid: 6664
DEBUG:bumble.host:>>> ACL packet enqueue: (CID=1) ACL: handle=0x0048, pb=0, bc=0, data_total_length=12, data=08000100071504004000081a
DEBUG:bumble.host:### HOST -> CONTROLLER: ACL: handle=0x0048, pb=0, bc=0, data_total_length=12, data=08000100071504004000081a
DEBUG:bumble.l2cap:Channel(64->6664, PSM=3, MTU=2048/48, state=OPEN) state change -> CLOSED
DEBUG:bumble.rfcomm:L2CAP channel closed, cleaning up
DEBUG:bumble.rfcomm:aborting DLC: DLC(dlci=2, state=CONNECTED, rx_max_frame_size=2000, rx_credits=32, rx_max_credits=32, tx_max_frame_size=1018, tx_credits=-1)
DEBUG:bumble.rfcomm:DLC(dlci=2, state=CONNECTED, rx_max_frame_size=2000, rx_credits=32, rx_max_credits=32, tx_max_frame_size=1018, tx_credits=-1) state change -> RESET
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_NUMBER_OF_COMPLETED_PACKETS_EVENT:
number_of_handles: 1
connection_handle[0]: 72
num_completed_packets[0]: 1
DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_LINK_SUPERVISION_TIMEOUT_CHANGED_EVENT:
connection_handle: 72
link_supervision_timeout: 32000
ERROR:bumble.hfp:HFP-HF protocol failed with the following error:
ERROR:bumble.hfp:Traceback (most recent call last):
File "C:\Program Files\WindowsApps\PythonSoftwareFoundation.Python.3.13_3.13.752.0_x64__qbz5n2kfra8p0\Lib\asyncio\tasks.py", line 507, in wait_for
return await fut
^^^^^^^^^
File "C:\Program Files\WindowsApps\PythonSoftwareFoundation.Python.3.13_3.13.752.0_x64__qbz5n2kfra8p0\Lib\asyncio\queues.py", line 186, in get
await getter
asyncio.exceptions.CancelledError

The above exception was the direct cause of the following exception:

Traceback (most recent call last):
File "C:\Users\KevinZhao\AppData\Local\Packages\PythonSoftwareFoundation.Python.3.13_qbz5n2kfra8p0\LocalCache\local-packages\Python313\site-packages\bumble\hfp.py", line 1139, in run
await self.initiate_slc()
File "C:\Users\KevinZhao\AppData\Local\Packages\PythonSoftwareFoundation.Python.3.13_qbz5n2kfra8p0\LocalCache\local-packages\Python313\site-packages\bumble\hfp.py", line 874, in initiate_slc
response = await self.execute_command(
^^^^^^^^^^^^^^^^^^^^^^^^^^^
f"AT+BRSF={self.supported_hf_features}", response_type=AtResponseType.SINGLE
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
)
^
File "C:\Users\KevinZhao\AppData\Local\Packages\PythonSoftwareFoundation.Python.3.13_qbz5n2kfra8p0\LocalCache\local-packages\Python313\site-packages\bumble\hfp.py", line 850, in execute_command
result = await asyncio.wait_for(
^^^^^^^^^^^^^^^^^^^^^^^
self.response_queue.get(), timeout=timeout
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
)
^
File "C:\Program Files\WindowsApps\PythonSoftwareFoundation.Python.3.13_3.13.752.0_x64__qbz5n2kfra8p0\Lib\asyncio\tasks.py", line 506, in wait_for
async with timeouts.timeout(timeout):
~~~~~~~~~~~~~~~~^^^^^^^^^
File "C:\Program Files\WindowsApps\PythonSoftwareFoundation.Python.3.13_3.13.752.0_x64__qbz5n2kfra8p0\Lib\asyncio\timeouts.py", line 116, in __aexit__
raise TimeoutError from exc_val
TimeoutError

DEBUG:bumble.host:### CONTROLLER -> HOST: HCI_DISCONNECTION_COMPLETE_EVENT:
status: HCI_SUCCESS
connection_handle: 72
reason: HCI_REMOTE_USER_TERMINATED_CONNECTION_ERROR
DEBUG:bumble.host:### DISCONNECTION: Connection(transport=0, peer_address=88:B9:45:3A:01:13/P), reason=19
DEBUG:bumble.device:*** Disconnection: [0x0048] 88:B9:45:3A:01:13/P as PERIPHERAL, reason=19
DEBUG:bumble.l2cap:disconnection from 72, cleaning up channels
```

I was looking through the script itself, and it seems like it does start an RFCOMM server, but isn't able to bind the connection to the phone. Does anyone have insight into this issue?