Exception in sink.on_packet: IndexError: index out of range

[acieszkiel]

Hello,

I have an issue when using hci_bridge.py to connect my local plugged BT USB dongle with Android Cuttlefish on AWS.

I run the following command at my local computer:
python3 ./apps/hci_bridge.py tcp-client:ec2-18-234-94-42.compute-1.amazonaws.com:18099 usb:0bda:a729

I run the following command at AWS:
python3 ./apps/hci_bridge.py tcp-server:localhost:7300 tcp-server:_:18099

When Android Cuttlefish is run the HCI commands start to be exchanged until an exception is thrown on the controller side (my local computer):
`

connecting to HCI...
connected
connecting to HCI...
connected
12:12:27.184 I bumble.bridge: [HOST->CONTROLLER] HCI_RESET_COMMAND
12:12:27.199 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 3
command_opcode: HCI_RESET_COMMAND
return_parameters:
status: SUCCESS
12:12:27.308 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_LOCAL_SUPPORTED_FEATURES_COMMAND
12:12:27.311 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_LOCAL_SUPPORTED_FEATURES_COMMAND
return_parameters:
status: SUCCESS
lmp_features: fffffffedbfd7b87
12:12:27.421 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_LOCAL_VERSION_INFORMATION_COMMAND
12:12:27.427 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_LOCAL_VERSION_INFORMATION_COMMAND
return_parameters:
status: SUCCESS
hci_version: BLUETOOTH_CORE_5_1
hci_subversion: 57286
lmp_version: BLUETOOTH_CORE_5_1
company_identifier: 93
lmp_subversion: 55586
12:12:27.538 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_BD_ADDR_COMMAND
12:12:27.542 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_BD_ADDR_COMMAND
return_parameters:
status: SUCCESS
bd_addr: 8A:88:4B:82:3F:E7/P
12:12:27.651 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_LOCAL_SUPPORTED_COMMANDS_COMMAND
12:12:27.661 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_LOCAL_SUPPORTED_COMMANDS_COMMAND
return_parameters:
status: SUCCESS
supported_commands: ffffff03ccffffff3ffffc9ff30fe8fe3fe78fff1c00000061f7ffff7f00e0fff7c007f8ffe38003000000000000000000000000000000000000000000000000
12:12:27.773 I bumble.bridge: [HOST->CONTROLLER] HCI_WRITE_EXTENDED_INQUIRY_RESPONSE_COMMAND:
fec_required: 0
extended_inquiry_response: 000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
12:12:27.778 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_WRITE_EXTENDED_INQUIRY_RESPONSE_COMMAND
return_parameters:
status: SUCCESS
12:12:27.887 I bumble.bridge: [HOST->CONTROLLER] HCI_WRITE_INQUIRY_MODE_COMMAND:
inquiry_mode: 2
12:12:27.890 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_WRITE_INQUIRY_MODE_COMMAND
return_parameters:
status: SUCCESS
12:12:27.999 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_INQUIRY_RESPONSE_TRANSMIT_POWER_LEVEL_COMMAND
12:12:28.003 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_INQUIRY_RESPONSE_TRANSMIT_POWER_LEVEL_COMMAND
return_parameters:
status: SUCCESS
tx_power: 0
12:12:28.113 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_LOCAL_EXTENDED_FEATURES_COMMAND:
page_number: 1
12:12:28.117 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_LOCAL_EXTENDED_FEATURES_COMMAND
return_parameters:
status: SUCCESS
page_number: 1
maximum_page_number: 2
extended_lmp_features: 0000000000000000
12:12:28.225 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_BUFFER_SIZE_COMMAND
12:12:28.230 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_BUFFER_SIZE_COMMAND
return_parameters:
status: SUCCESS
hc_acl_data_packet_length: 1021
hc_synchronous_data_packet_length: 255
hc_total_num_acl_data_packets: 6
hc_total_num_synchronous_data_packets: 12
12:12:28.340 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_CLASS_OF_DEVICE_COMMAND
12:12:28.344 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_CLASS_OF_DEVICE_COMMAND
return_parameters:
status: SUCCESS
class_of_device: [000000] Services(),Class(Miscellaneous|#00)
12:12:28.453 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_LOCAL_NAME_COMMAND
12:12:28.474 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_LOCAL_NAME_COMMAND
return_parameters:
status: SUCCESS
local_name: RTK_BT_5.0
12:12:28.584 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_VOICE_SETTING_COMMAND
12:12:28.588 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_VOICE_SETTING_COMMAND
return_parameters:
status: SUCCESS
voice_setting: 96
12:12:28.698 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_NUMBER_OF_SUPPORTED_IAC_COMMAND
12:12:28.703 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_NUMBER_OF_SUPPORTED_IAC_COMMAND
return_parameters:
status: SUCCESS
num_support_iac: 1
12:12:28.812 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_CURRENT_IAC_LAP_COMMAND
12:12:28.817 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_CURRENT_IAC_LAP_COMMAND
return_parameters:
status: SUCCESS
num_support_iac: 1
iac_lap: 338b9e
12:12:28.927 I bumble.bridge: [HOST->CONTROLLER] HCI_WRITE_CONNECTION_ACCEPT_TIMEOUT_COMMAND:
connection_accept_timeout: 32000
12:12:28.930 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_WRITE_CONNECTION_ACCEPT_TIMEOUT_COMMAND
return_parameters:
status: SUCCESS
12:12:29.038 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_LOCAL_SUPPORTED_FEATURES_COMMAND
12:12:29.042 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_LOCAL_SUPPORTED_FEATURES_COMMAND
return_parameters:
status: SUCCESS
le_features: bd5f660000000000
12:12:29.151 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_BUFFER_SIZE_COMMAND
12:12:29.155 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_BUFFER_SIZE_COMMAND
return_parameters:
status: SUCCESS
le_acl_data_packet_length: 27
total_num_le_acl_data_packets: 8
12:12:29.264 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_SUPPORTED_STATES_COMMAND
12:12:29.269 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_SUPPORTED_STATES_COMMAND
return_parameters:
status: SUCCESS
le_states: ffffffffff030000
12:12:29.377 I bumble.bridge: [HOST->CONTROLLER] HCI_SET_EVENT_MASK_COMMAND:
event_mask: fffffbff07d8bf3d
12:12:29.381 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_SET_EVENT_MASK_COMMAND
return_parameters:
status: SUCCESS
12:12:29.489 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_STORED_LINK_KEY_COMMAND:
bd_addr: 00:00:00:00:00:00/P
read_all_flag: 1
12:12:29.494 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_STORED_LINK_KEY_COMMAND
return_parameters:
status: SUCCESS
max_num_keys: 7
num_keys_read: 0
12:12:29.603 I bumble.bridge: [HOST->CONTROLLER] HCI_WRITE_DEFAULT_LINK_POLICY_SETTINGS_COMMAND:
default_link_policy_settings: 15
12:12:29.608 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_WRITE_DEFAULT_LINK_POLICY_SETTINGS_COMMAND
return_parameters:
status: SUCCESS
12:12:29.717 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_PAGE_SCAN_ACTIVITY_COMMAND
12:12:29.720 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_PAGE_SCAN_ACTIVITY_COMMAND
return_parameters:
status: SUCCESS
page_scan_interval: 2048
page_scan_window: 18
12:12:29.829 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_DEFAULT_ERRONEOUS_DATA_REPORTING_COMMAND
12:12:29.833 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_DEFAULT_ERRONEOUS_DATA_REPORTING_COMMAND
return_parameters:
status: SUCCESS
erroneous_data_reporting: 0
12:12:29.943 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_PAGE_SCAN_TYPE_COMMAND
12:12:29.949 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_PAGE_SCAN_TYPE_COMMAND
return_parameters:
status: SUCCESS
page_scan_type: 0
12:12:30.058 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_LOCAL_EXTENDED_FEATURES_COMMAND:
page_number: 2
12:12:30.064 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_LOCAL_EXTENDED_FEATURES_COMMAND
return_parameters:
status: SUCCESS
page_number: 2
maximum_page_number: 2
extended_lmp_features: 5f02000000000000
12:12:30.173 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_SET_EVENT_MASK_COMMAND:
le_event_mask: df1f0a0000000000
12:12:30.178 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_SET_EVENT_MASK_COMMAND
return_parameters:
status: SUCCESS
12:12:30.287 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_TRANSMIT_POWER_COMMAND
12:12:30.292 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_TRANSMIT_POWER_COMMAND
return_parameters:
status: SUCCESS
min_tx_power: 237
max_tx_power: 23
12:12:30.401 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_FILTER_ACCEPT_LIST_SIZE_COMMAND
12:12:30.406 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_FILTER_ACCEPT_LIST_SIZE_COMMAND
return_parameters:
status: SUCCESS
filter_accept_list_size: 32
12:12:30.515 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_CLEAR_FILTER_ACCEPT_LIST_COMMAND
12:12:30.519 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_CLEAR_FILTER_ACCEPT_LIST_COMMAND
return_parameters:
status: SUCCESS
12:12:30.627 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_MAXIMUM_DATA_LENGTH_COMMAND
12:12:30.632 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_MAXIMUM_DATA_LENGTH_COMMAND
return_parameters:
status: SUCCESS
supported_max_tx_octets: 251
supported_max_tx_time: 17040
supported_max_rx_octets: 251
supported_max_rx_time: 17040
12:12:30.740 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_SUGGESTED_DEFAULT_DATA_LENGTH_COMMAND
12:12:30.744 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_SUGGESTED_DEFAULT_DATA_LENGTH_COMMAND
return_parameters:
status: SUCCESS
suggested_max_tx_octets: 251
suggested_max_tx_time: 17040
12:12:30.852 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_NUMBER_OF_SUPPORTED_ADVERTISING_SETS_COMMAND
12:12:30.856 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_NUMBER_OF_SUPPORTED_ADVERTISING_SETS_COMMAND
return_parameters:
status: SUCCESS
num_supported_advertising_sets: 4
12:12:30.965 I bumble.bridge: [HOST->CONTROLLER] HCI_DELETE_STORED_LINK_KEY_COMMAND:
bd_addr: 00:00:00:00:00:00/P
delete_all_flag: 1
12:12:30.970 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_DELETE_STORED_LINK_KEY_COMMAND
return_parameters:
status: SUCCESS
num_keys_deleted: 0
12:12:31.078 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_SYNCHRONIZATION_TRAIN_PARAMETERS_COMMAND
12:12:31.082 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_SYNCHRONIZATION_TRAIN_PARAMETERS_COMMAND
return_parameters:
data: 0000000000000000
12:12:31.190 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_WRITE_SUGGESTED_DEFAULT_DATA_LENGTH_COMMAND:
suggested_max_tx_octets: 251
suggested_max_tx_time: 17040
12:12:31.194 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_WRITE_SUGGESTED_DEFAULT_DATA_LENGTH_COMMAND
return_parameters:
status: SUCCESS
12:12:31.303 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_SET_DEFAULT_PHY_COMMAND:
all_phys: ANY_TX
tx_phys: None
rx_phys: None
12:12:31.308 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_SET_DEFAULT_PHY_COMMAND
return_parameters:
status: SUCCESS
12:12:31.416 I bumble.bridge: [HOST->CONTROLLER] HCI_RESET_COMMAND
12:12:31.430 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 3
command_opcode: HCI_RESET_COMMAND
return_parameters:
status: SUCCESS
12:12:31.540 I bumble.bridge: [HOST->CONTROLLER] HCI_RESET_COMMAND
12:12:31.553 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 3
command_opcode: HCI_RESET_COMMAND
return_parameters:
status: SUCCESS
12:12:31.663 I bumble.bridge: [HOST->CONTROLLER] HCI_SET_EVENT_MASK_COMMAND:
event_mask: ffffffffffffbf3d
12:12:31.667 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_SET_EVENT_MASK_COMMAND
return_parameters:
status: SUCCESS
12:12:31.775 I bumble.bridge: [HOST->CONTROLLER] HCI_SET_EVENT_MASK_PAGE_2_COMMAND:
event_mask_page_2: 0000000200000000
12:12:31.779 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_SET_EVENT_MASK_PAGE_2_COMMAND
return_parameters:
status: SUCCESS
12:12:31.886 I bumble.bridge: [HOST->CONTROLLER] HCI_WRITE_LE_HOST_SUPPORT_COMMAND:
le_supported_host: 1
simultaneous_le_host: 0
12:12:31.890 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_WRITE_LE_HOST_SUPPORT_COMMAND
return_parameters:
status: SUCCESS
12:12:31.999 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_LOCAL_NAME_COMMAND
12:12:32.018 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_LOCAL_NAME_COMMAND
return_parameters:
status: SUCCESS
local_name: RTK_BT_5.0
12:12:32.127 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_LOCAL_VERSION_INFORMATION_COMMAND
12:12:32.132 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_LOCAL_VERSION_INFORMATION_COMMAND
return_parameters:
status: SUCCESS
hci_version: BLUETOOTH_CORE_5_1
hci_subversion: 57286
lmp_version: BLUETOOTH_CORE_5_1
company_identifier: 93
lmp_subversion: 55586
12:12:32.241 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_LOCAL_SUPPORTED_COMMANDS_COMMAND
12:12:32.251 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_LOCAL_SUPPORTED_COMMANDS_COMMAND
return_parameters:
status: SUCCESS
supported_commands: ffffff03ccffffff3ffffc9ff30fe8fe3fe78fff1c00000061f7ffff7f00e0fff7c007f8ffe38003000000000000000000000000000000000000000000000000
12:12:32.359 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_LOCAL_SUPPORTED_FEATURES_COMMAND
12:12:32.364 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_LOCAL_SUPPORTED_FEATURES_COMMAND
return_parameters:
status: SUCCESS
le_features: bd5f660000000000
12:12:32.472 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_SUPPORTED_STATES_COMMAND
12:12:32.477 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_SUPPORTED_STATES_COMMAND
return_parameters:
status: SUCCESS
le_states: ffffffffff030000
12:12:32.586 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_LOCAL_EXTENDED_FEATURES_COMMAND:
page_number: 0
12:12:32.592 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_LOCAL_EXTENDED_FEATURES_COMMAND
return_parameters:
status: SUCCESS
page_number: 0
maximum_page_number: 2
extended_lmp_features: fffffffedbfd7b87
12:12:32.701 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_LOCAL_EXTENDED_FEATURES_COMMAND:
page_number: 1
12:12:32.707 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_LOCAL_EXTENDED_FEATURES_COMMAND
return_parameters:
status: SUCCESS
page_number: 1
maximum_page_number: 2
extended_lmp_features: 0200000000000000
12:12:32.816 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_LOCAL_EXTENDED_FEATURES_COMMAND:
page_number: 2
12:12:32.822 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_LOCAL_EXTENDED_FEATURES_COMMAND
return_parameters:
status: SUCCESS
page_number: 2
maximum_page_number: 2
extended_lmp_features: 5f02000000000000
12:12:32.931 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_SET_EVENT_MASK_COMMAND:
le_event_mask: 7ffe020000000000
12:12:32.936 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_SET_EVENT_MASK_COMMAND
return_parameters:
status: SUCCESS
12:12:33.045 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_BUFFER_SIZE_COMMAND
12:12:33.050 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_BUFFER_SIZE_COMMAND
return_parameters:
status: SUCCESS
hc_acl_data_packet_length: 1021
hc_synchronous_data_packet_length: 255
hc_total_num_acl_data_packets: 6
hc_total_num_synchronous_data_packets: 12
12:12:33.159 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_BUFFER_SIZE_COMMAND
12:12:33.164 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_BUFFER_SIZE_COMMAND
return_parameters:
status: SUCCESS
le_acl_data_packet_length: 27
total_num_le_acl_data_packets: 8
12:12:33.273 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_FILTER_ACCEPT_LIST_SIZE_COMMAND
12:12:33.278 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_FILTER_ACCEPT_LIST_SIZE_COMMAND
return_parameters:
status: SUCCESS
filter_accept_list_size: 32
12:12:33.386 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_MAXIMUM_DATA_LENGTH_COMMAND
12:12:33.391 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_MAXIMUM_DATA_LENGTH_COMMAND
return_parameters:
status: SUCCESS
supported_max_tx_octets: 251
supported_max_tx_time: 17040
supported_max_rx_octets: 251
supported_max_rx_time: 17040
12:12:33.500 I bumble.bridge: [HOST->CONTROLLER] HCI_WRITE_SIMPLE_PAIRING_MODE_COMMAND:
simple_pairing_mode: 1
12:12:33.504 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_WRITE_SIMPLE_PAIRING_MODE_COMMAND
return_parameters:
status: SUCCESS
12:12:33.613 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_SUGGESTED_DEFAULT_DATA_LENGTH_COMMAND
12:12:33.618 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_SUGGESTED_DEFAULT_DATA_LENGTH_COMMAND
return_parameters:
status: SUCCESS
suggested_max_tx_octets: 251
suggested_max_tx_time: 17040
12:12:33.727 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_MAXIMUM_ADVERTISING_DATA_LENGTH_COMMAND
12:12:33.731 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_MAXIMUM_ADVERTISING_DATA_LENGTH_COMMAND
return_parameters:
status: SUCCESS
max_advertising_data_length: 1024
12:12:33.839 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_READ_NUMBER_OF_SUPPORTED_ADVERTISING_SETS_COMMAND
12:12:33.845 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_LE_READ_NUMBER_OF_SUPPORTED_ADVERTISING_SETS_COMMAND
return_parameters:
status: SUCCESS
num_supported_advertising_sets: 4
12:12:33.954 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_DEFAULT_ERRONEOUS_DATA_REPORTING_COMMAND
12:12:33.958 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_DEFAULT_ERRONEOUS_DATA_REPORTING_COMMAND
return_parameters:
status: SUCCESS
erroneous_data_reporting: 0
12:12:34.067 I bumble.bridge: [HOST->CONTROLLER] [OGF=0x3f, OCF=0x0153]
12:12:34.071 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: [OGF=0x3f, OCF=0x0153]
return_parameters:
data: 01
12:12:34.188 I bumble.bridge: [HOST->CONTROLLER] HCI_WRITE_DEFAULT_ERRONEOUS_DATA_REPORTING_COMMAND: 01
12:12:34.194 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_WRITE_DEFAULT_ERRONEOUS_DATA_REPORTING_COMMAND
return_parameters:
data: 00
12:12:34.305 I bumble.bridge: [HOST->CONTROLLER] HCI_READ_BD_ADDR_COMMAND
12:12:34.309 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: HCI_READ_BD_ADDR_COMMAND
return_parameters:
status: SUCCESS
bd_addr: 8A:88:4B:82:3F:E7/P
12:12:34.432 I bumble.bridge: [HOST->CONTROLLER] HCI_LE_CS_READ_LOCAL_SUPPORTED_CAPABILITIES_COMMAND
12:12:34.436 E bumble.transport.usb: !!! Exception in sink.on_packet
Traceback (most recent call last):
File "/home/adam/bumble_latest/bumble/bumble/transport/usb.py", line 294, in dequeue
self.sink.on_packet(packet)
File "/home/adam/bumble_latest/bumble/bumble/bridge.py", line 40, in on_packet
hci_packet = HCI_Packet.from_bytes(packet)
File "/home/adam/bumble_latest/bumble/bumble/hci.py", line 2394, in from_bytes
return HCI_Event.from_bytes(packet)
File "/home/adam/bumble_latest/bumble/bumble/hci.py", line 6203, in from_bytes
return subclass.from_parameters(parameters)
File "/home/adam/bumble_latest/bumble/bumble/hci.py", line 7368, in from_parameters
event.return_parameters = subclass.parse_return_parameters(
File "/home/adam/bumble_latest/bumble/bumble/hci.py", line 2618, in parse_return_parameters
return cls.return_parameters_class.from_parameters(parameters)
File "/home/adam/bumble_latest/bumble/bumble/hci.py", line 2559, in from_parameters
return cls(**HCI_Object.dict_from_bytes(parameters, 0, cls.fields))
File "/home/adam/bumble_latest/bumble/bumble/hci.py", line 1914, in dict_from_bytes
return HCI_Object.dict_and_offset_from_bytes(data, offset, fields)[1]
File "/home/adam/bumble_latest/bumble/bumble/hci.py", line 1904, in dict_and_offset_from_bytes
field_value, field_size = HCI_Object.parse_field(
File "/home/adam/bumble_latest/bumble/bumble/hci.py", line 1847, in parse_field
return (data[offset], 1)
IndexError: index out of range
12:12:36.433 I bumble.bridge: [HOST->CONTROLLER] [OGF=0x3f, OCF=0x015b]
12:12:36.438 I bumble.bridge: [CONTROLLER->HOST] HCI_COMMAND_COMPLETE_EVENT:
num_hci_command_packets: 2
command_opcode: [OGF=0x3f, OCF=0x015b]
return_parameters:
data: 01
`

That problem did not exist a year ago with the same set-up. When I reverted bumble to fe3fa3d it works well again.

My set-up:
BT dongle:
Bus 001 Device 005: ID 0bda:a729 Realtek Semiconductor Corp. Bluetooth 5.3 Radio
[ 37.938108] Bluetooth: hci0: RTL: loading rtl_bt/rtl8761bu_fw.bin
[ 37.946228] Bluetooth: hci0: RTL: loading rtl_bt/rtl8761bu_config.bin

Bumble: latest

Android Cuttlefish: release 15

[barbibulle]

Likely something I broken in a recent PR that updated how HCI command return parameters are parsed, and wasn't caught in unit tests. I'll take a look today and submit a fix.

[barbibulle]

I'm not yet able to reproduce this issue, because in my setting the Android stack doesn't issue the same HCI commands (different controllers).
From the log here it looks like the error happens when parsing the return parameters of the HCI_LE_CS_READ_LOCAL_SUPPORTED_CAPABILITIES command. A quick check of the spec seems to match the way the structure is defined in the code, but maybe I'm missing something.
Also, I'm surprised that the Android stack would even send this command, because the controller you have doesn't declare supporting channel sounding (which is a Bluetooth 6 feature, and your controller declared Bluetooth 5.1). Did you set a debug/config flag in your Android image to force the stack to still issue that command?

In order to help troubleshoot this, I've pushed PR #877 which should print out the packet when a parsing error occurs (and forward the packet as-is, so it won't stop the bridge). If you could try it, and look at the log to see the hex value of the packet data when the exception is printed out, that would help.