2 Replies Latest reply on Sep 24, 2020 12:10 PM by JaPe_1712546

    BR/EDR Reconnect After Pairing

    JaPe_1712546

      I am using the modus toolbox with the wiced_btsdk 2.7. I am using the HCI_Audio_Gateway demo. I can pair and connect to many different handsfree devices just fine but I cannot reconnect to some of them after a power cycle. I am only pairing/connecting with one device at a time and I am not using BLE. I am making sure to save the NVRAM data and restore it after the power cycle. I have tried adding bonding to the demo application but still not having success. The failure occurs in the SDP, the wiced callback gives us a status of 0xFFF1. I have attached logs of the process including the nvram data tracing. Please help, this is very urgent, and I can't think of anything else this could be except a bug in the BT stack.

       

       

      Log from initial pairing:

       

       

       

       

      TRACE EVENT: WICED 22 - inquiry started:8100

      :

      Command status: Success

      TRACE EVENT: WICED 35 - inquiry result 74 5c 4b f6 16 a1

      :

      TRACE EVENT: WICED 95 - p_inquiry_result: co:34119, dev: 2215908, eir_c: 1, eir: 8393986,0, pmd: 0, pper: 0, prep: 1, :

      Device found: 74:5C:4B:F6:16:A1

      device_class 24:04:04 rssi:186

      name: Jabra E

      --

       

       

      Inquiry Complete: 1

      Best: RSSI: 186 name: Jabra

      TRACE EVENT: WICED 37 - hci_control_proc_rx_cmd:10, 21e27c

      :

      TRACE EVENT: WICED 17 - cmd_opcode 0x10

      :

      TRACE EVENT: WICED 56 - Attempting to bond to: 74 5c 4b f6 16 a1 , status: 8100:

      Command status: Success

      TRACE EVENT: WICED 35 - hci_control_management_callback 8

      :

      TRACE EVENT: WICED 64 - BTM_PAIRING_IO_CAPABILITIES_REQUEST_EVT bda 74 5c 4b f6 16 a1

      :

      TRACE EVENT: WICED 35 - hci_control_management_callback 9

      :

      TRACE EVENT: WICED 49 -

      BTM_PAIRING_IO_CAPABILITIES_BR_EDR_RESPONSE_EVT:

      TRACE EVENT: WICED 87 -

      peer_bd_addr: 74 5c 4b f6 16 a1 , peer_io_cap: 3, peer_oob_data: 0, peer_auth_req: 4:

      TRACE EVENT: WICED 35 - hci_control_management_callback 4

      :

      TRACE EVENT: WICED 36 - hci_control_management_callback 18

      :

      TRACE EVENT: WICED 28 - hci_control_alloc_nvram_id

      :

      TRACE EVENT: WICED 31 - hci_control_alloc_nvram_id:16

      :

      TRACE EVENT: WICED 23 - Allocated NVRAM ID:16

      :

      TRACE EVENT: WICED 41 - Updated Addr Resolution DB:8113. ID: 16

      :

      TRACE EVENT: WICED 6 - key: :

      TRACE EVENT: WICED 13 - 74 5c 4b f6 :

      TRACE EVENT: WICED 13 - 16 a1 04 76 :

      TRACE EVENT: WICED 13 - 03 3c a1 f6 :

      TRACE EVENT: WICED 13 - 28 ea c0 ac :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - c0 8f ce 62 :

      TRACE EVENT: WICED 13 - 44 17 28 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 04 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 7 - 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 2 -

      :

      NVRAM ID: 16  Length: 138

      TRACE EVENT: WICED 55 - NVRAM write:id:16 bytes:138 dev: [74 5c 4b f6 16 a1 ]

      :

      TRACE EVENT: WICED 36 - hci_control_management_callback 11

      :

      Pairing complete Success

      ending the pairing complete evt: 74 5c 4b f6 16 a1  as a1 16 f6 4b 5c 74  status 0

      :

      DEBUG (902): New state: BT_connection_state_CONNECTING

      DEBUG (698): BT_EVENT_CONNECTING

      Sending AG Connect 74:5C:4B:F6:16:A1:

      TRACE EVENT: WICED 36 - hci_control_proc_rx_cmd:5, 21e2a0

      :

      TRACE EVENT: WICED 17 - cmd_opcode 0x07

      :

      TRACE EVENT: WICED 21 - cancel inquiry:8105

      :

      Command status: Success

      TRACE EVENT: WICED 37 - hci_control_proc_rx_cmd:10, 21e2e8

      :

      TRACE EVENT: WICED 18 - cmd_opcode 0xe01

      :

      TRACE EVENT: WICED 27 - advanced connection ret: 0:

      TRACE EVENT: WICED 36 - Security State: status: 0, flags: 0:

      TRACE EVENT: WICED 85 - Bonded Devices: status: 167, num: 1, addr_type: 33, addr: a9 05 0d 00 a0 e2  type: 0:

      TRACE EVENT: WICED 85 -   initiate service discovery app_handle = 1, uuid: 4382, num_attr: 4, p_scp: 21b120

      :

      TRACE EVENT: WICED 57 -   search attribute request,  74 5c 4b f6 16 a1 , 22c794

      :

      TRACE EVENT: WICED 51 - hci_control_ag_sdp_cback status:0x0, p_scb 21b120

      :

      TRACE EVENT: WICED 25 - Looking for HFP service

      :

      TRACE EVENT: WICED 67 - hci_control_ag_sdp_find_attr - num of proto elements -RFCOMM =0x1

      :

      TRACE EVENT: WICED 65 - hci_control_ag_sdp_find_attr - found SCN in SDP record. SCN=0x1

      :

      TRACE EVENT: WICED 34 - HF device profile version: 0x106

      :

      TRACE EVENT: WICED 51 - [1]hfp_ag_rfcomm_do_open: rfc_serv_handle: 0x0000

      :

      TRACE EVENT: WICED 79 - [1]hfp_ag_rfcomm_do_open - rfcomm_create Res: 0x0   Port: 0x0007 UUID: 0x111e

      :

      TRACE EVENT: WICED 36 - hci_control_management_callback 12

      :

      TRACE EVENT: WICED 58 - Encryption Status Event: bd ( 74 5c 4b f6 16 a1  ) res 0

      :

      Encryption changed:0

      TRACE EVENT: WICED 100 - [1]hfp_ag_rfcomm_control_callback : Status = 0, port: 0x0007  SCB state: 1  Srv: 0x0000  Conn: 0x000:

      TRACE EVENT: WICED 39 - hfp_ag_process_open_callback status=0

      :

      TRACE EVENT: WICED 59 - [1]hfp_ag_hci_send_ag_event: Sending Event: 3585  to UART

      :

      HCI_CONTROL_AG_EVENT_OPEN HF: 0

      [Handle: 1]  BDA: 74:5C:4B:F6:16:A1

      TRACE EVENT: WICED 61 - [1]RFCOMM Connected  isInit: 1  Serv: 0x0000   Conn: 0x0007

      :

      TRACE EVENT: WICED 84 - hfp_ag_sco_create  is_orig: 0   sco_idx: 0x0  status:0x1fa4 retry_with_sco_only: 0

      :

      TRACE EVENT: WICED 47 - HFP AT cmd:AT+BRSF arg_type:2 arg:159 arg:159

      :

      TRACE EVENT: WICED 31 - BRSF HF : 0x9f , phone : 0xa00:

      TRACE EVENT: WICED 56 - [1]Sent AT response[0x06][ret 0][hndl 7]: +BRSF: 2560

      :

      TRACE EVENT: WICED 30 - [1]Sent AT fail[0x00][ret 3]

      :

      TRACE EVENT: WICED 44 - HFP AT cmd:AT+BAC arg_type:2 arg:0 arg:1,2

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 7]: OK

      :

      TRACE EVENT: WICED 44 - HFP AT cmd:AT+CIND arg_type:8 arg:0 arg:=?

      :

      TRACE EVENT: WICED 100 - [1]Sent AT response[0x03][ret 0][hndl 7]: +CIND: ("call",(0,1)),("callsetup",(0-3)),("callheld",(0-2:

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 7]: OK

      :

      TRACE EVENT: WICED 43 - HFP AT cmd:AT+CIND arg_type:4 arg:0 arg:?

      :

      TRACE EVENT: WICED 59 - [1]Sent AT response[0x03][ret 0][hndl 7]: +CIND: 1,0,0,1

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 7]: OK

      :

      TRACE EVENT: WICED 52 - HFP AT cmd:AT+CMER arg_type:2 arg:0 arg:3, 0, 0, 1

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 7]: OK

      :

      TRACE EVENT: WICED 59 - [1]hfp_ag_hci_send_ag_event: Sending Event: 3587  to UART

      :

      HCI_CONTROL_AG_EVENT_CONNECTED [Handle: 1]

      features: 0x009F

      BT Wideband audio supported

      Echo cancel Noise reduce

      Voice recognition

      Volume control

      TRACE EVENT: WICED 43 - HFP AT cmd:AT+VGS arg_type:2 arg:7 arg:07

      :

      DEBUG (937): New state: BT_connection_state_CONNECTED

      DEBUG (710): BT_EVENT_CONNECTED_AFTER_PAIRING

      DEBUG (695): BT_EVENT_CONNECTED

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 7]: OK

      :

      TRACE EVENT: WICED 43 - HFP AT cmd:AT+VGM arg_type:2 arg:9 arg:09

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 7]: OK

      :

      TRACE EVENT: WICED 54 - hfp_ag_parse_AT_command: BTA_AG_ERR_OP_NOT_SUPPORTED

      :

      TRACE EVENT: WICED 50 - [1]Sent AT response[0x01][ret 0][hndl 7]: ERROR

      :

      TRACE EVENT: WICED 43 - HFP AT cmd:AT+BTRH arg_type:4 arg:0 arg:?

      :

      TRACE EVENT: WICED 50 - [1]Sent AT response[0x01][ret 0][hndl 7]: ERROR

      :

      TRACE EVENT: WICED 43 - HFP AT cmd:AT+CLIP arg_type:2 arg:1 arg:1

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 7]: OK

      :

      TRACE EVENT: WICED 50 - HFP AT cmd:AT+BIA arg_type:2 arg:0 arg:1,1,1,0,0

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 7]: OK

      :

      Sending AG open 74:5C:4B:F6:16:A1:

      TRACE EVENT: WICED 36 - hci_control_proc_rx_cmd:6, 21e258

      :

      TRACE EVENT: WICED 18 - cmd_opcode 0xe03

      :

      TRACE EVENT: WICED 45 - hfp_ag_audio_open - state: 2  SCO inx: 0x00

      :

      TRACE EVENT: WICED 21 - send +BCS codec is 2:

      TRACE EVENT: WICED 52 - [1]Sent AT response[0x07][ret 0][hndl 7]: +BCS: 2

      :

      TRACE EVENT: WICED 42 - HFP AT cmd:AT+BCS arg_type:2 arg:2 arg:2

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 7]: OK

      :

      TRACE EVENT: WICED 9 - Use WBS

      :

      TRACE EVENT: WICED 84 - hfp_ag_sco_create  is_orig: 1   sco_idx: 0x0  status:0x1fa4 retry_with_sco_only: 1

      :

      TRACE EVENT: WICED 36 - hci_control_management_callback 27

      :

      TRACE EVENT: WICED 39 - Got BTM_SCO_CONNECTED_EVT: sco_idx: 0

      :

      TRACE EVENT: WICED 59 - [1]hfp_ag_hci_send_ag_event: Sending Event: 3588  to UART

      :

      HCI_CONTROL_AG_EVENT_AUDIO_OPEN [Handle: 1]

      Wideband mode selected

      DEBUG (960): New state: BT_connection_state_AUDIO_CONNECTED

      DEBUG (689): BT_EVENT_AUDIO_CONNECTED

      TRACE EVENT: WICED 35 - hci_control_management_callback 2

      :

      Err

      Error: Command not found.

       

       

      btld Set Bluetooth state

      Sending AG close 74:5C:4B:F6:16:A1:

      Sending AG disconnect 74:5C:4B:F6:16:A1:

      TRACE EVENT: WICED 36 - hci_control_proc_rx_cmd:6, 21e354

      :

      TRACE EVENT: WICED 18 - cmd_opcode 0xe04

      :

      TRACE EVENT: WICED 32 - hfp_ag_sco_close : sco_idx = 0

      :

      TRACE EVENT: WICED 36 - hci_control_proc_rx_cmd:6, 21e2e8

      :

      TRACE EVENT: WICED 18 - cmd_opcode 0xe02

      :

      TRACE EVENT: WICED 33 - [1]hfp_ag_disconnect   State: 2

      :

      TRACE EVENT: WICED 32 - hfp_ag_sco_close : sco_idx = 0

      :

      TRACE EVENT: WICED 36 - hci_control_management_callback 28

      :

      TRACE EVENT: WICED 42 - Got BTM_SCO_DISCONNECTED_EVT: sco_idx: 0

      :

      TRACE EVENT: WICED 47 - [1]hfp_ag_rfcomm_do_close (0x0007) result 0x0

      :

      TRACE EVENT: WICED 100 - [1]hfp_ag_rfcomm_control_callback : Status = 19, port: 0x0007  SCB state: 3  Srv: 0x0000  Conn: 0x00:

      TRACE EVENT: WICED 59 - [1]hfp_ag_hci_send_ag_event: Sending Event: 3586  to UART

      :

      HCI_CONTROL_AG_EVENT_CLOSE: unknown

      TRACE EVENT: WICED 45 - [1]hfp_ag_rfcomm_closed (0x0007) result 0x0

      :

      TRACE EVENT: WICED 36 - hfp_ag_sco_close : sco_idx = 65535

      :

      TRACE EVENT: WICED 82 - [1]hfp_ag_rfcomm_start_server: rfcomm_create Res: 0x0  Port: 0x0001 UUID: 0x111e

      :

      DEBUG (937): New state: BT_connection_state_CONNECTED

      DEBUG (695): BT_EVENT_CONNECTED

      TRACE EVENT: WICED 35 - hci_control_management_callback 2

      :

      DEBUG (685): BT_EVENT_DISCONNECTED

      DEBUG (902): New state: BT_connection_state_CONNECTING

      DEBUG (698): BT_EVENT_CONNECTING

      Sending AG Connect 74:5C:4B:F6:16:A1:

      TRACE EVENT: WICED 37 - hci_control_proc_rx_cmd:10, 21e354

      :

      TRACE EVENT: WICED 18 - cmd_opcode 0xe01

      :

      TRACE EVENT: WICED 27 - advanced connection ret: 0:

      TRACE EVENT: WICED 36 - Security State: status: 0, flags: 0:

      TRACE EVENT: WICED 85 - Bonded Devices: status: 167, num: 1, addr_type: 33, addr: a9 05 0d 00 58 e2  type: 0:

      TRACE EVENT: WICED 85 -   initiate service discovery app_handle = 1, uuid: 4382, num_attr: 4, p_scp: 21b120

      :

      TRACE EVENT: WICED 57 -   search attribute request,  74 5c 4b f6 16 a1 , 22bd68

      :

      TRACE EVENT: WICED 51 - hci_control_ag_sdp_cback status:0x0, p_scb 21b120

      :

      TRACE EVENT: WICED 25 - Looking for HFP service

      :

      TRACE EVENT: WICED 67 - hci_control_ag_sdp_find_attr - num of proto elements -RFCOMM =0x1

      :

      TRACE EVENT: WICED 65 - hci_control_ag_sdp_find_attr - found SCN in SDP record. SCN=0x1

      :

      TRACE EVENT: WICED 34 - HF device profile version: 0x106

      :

      TRACE EVENT: WICED 51 - [1]hfp_ag_rfcomm_do_open: rfc_serv_handle: 0x0000

      :

      TRACE EVENT: WICED 79 - [1]hfp_ag_rfcomm_do_open - rfcomm_create Res: 0x0   Port: 0x0002 UUID: 0x111e

      :

      TRACE EVENT: WICED 100 - [1]hfp_ag_rfcomm_control_callback : Status = 0, port: 0x0002  SCB state: 1  Srv: 0x0000  Conn: 0x000:

      TRACE EVENT: WICED 39 - hfp_ag_process_open_callback status=0

      :

      TRACE EVENT: WICED 59 - [1]hfp_ag_hci_send_ag_event: Sending Event: 3585  to UART

      :

      HCI_CONTROL_AG_EVENT_OPEN HF: 0

      [Handle: 1]  BDA: 74:5C:4B:F6:16:A1

      TRACE EVENT: WICED 61 - [1]RFCOMM Connected  isInit: 1  Serv: 0x0000   Conn: 0x0002

      :

      TRACE EVENT: WICED 84 - hfp_ag_sco_create  is_orig: 0   sco_idx: 0x0  status:0x1fa4 retry_with_sco_only: 0

      :

      TRACE EVENT: WICED 47 - HFP AT cmd:AT+BRSF arg_type:2 arg:159 arg:159

      :

      TRACE EVENT: WICED 31 - BRSF HF : 0x9f , phone : 0xa00:

      TRACE EVENT: WICED 56 - [1]Sent AT response[0x06][ret 0][hndl 2]: +BRSF: 2560

      :

      TRACE EVENT: WICED 30 - [1]Sent AT fail[0x00][ret 3]

      :

      TRACE EVENT: WICED 44 - HFP AT cmd:AT+BAC arg_type:2 arg:0 arg:1,2

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 2]: OK

      :

      TRACE EVENT: WICED 44 - HFP AT cmd:AT+CIND arg_type:8 arg:0 arg:=?

      :

      TRACE EVENT: WICED 100 - [1]Sent AT response[0x03][ret 0][hndl 2]: +CIND: ("call",(0,1)),("callsetup",(0-3)),("callheld",(0-2:

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 2]: OK

      :

      TRACE EVENT: WICED 43 - HFP AT cmd:AT+CIND arg_type:4 arg:0 arg:?

      :

      TRACE EVENT: WICED 59 - [1]Sent AT response[0x03][ret 0][hndl 2]: +CIND: 1,0,0,1

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 2]: OK

      :

      TRACE EVENT: WICED 52 - HFP AT cmd:AT+CMER arg_type:2 arg:0 arg:3, 0, 0, 1

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 2]: OK

      :

      TRACE EVENT: WICED 59 - [1]hfp_ag_hci_send_ag_event: Sending Event: 3587  to UART

      :

      HCI_CONTROL_AG_EVENT_CONNECTED [Handle: 1]

      features: 0x009F

      BT Wideband audio supported

      Echo cancel Noise reduce

      Voice recognition

      Volume control

      TRACE EVENT: WICED 43 - HFP AT cmd:AT+VGS arg_type:2 arg:7 arg:07

      :

      DEBUG (937): New state: BT_connection_state_CONNECTED

      DEBUG (695): BT_EVENT_CONNECTED

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 2]: OK

      :

      TRACE EVENT: WICED 43 - HFP AT cmd:AT+VGM arg_type:2 arg:9 arg:09

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 2]: OK

      :

      TRACE EVENT: WICED 54 - hfp_ag_parse_AT_command: BTA_AG_ERR_OP_NOT_SUPPORTED

      :

      TRACE EVENT: WICED 50 - [1]Sent AT response[0x01][ret 0][hndl 2]: ERROR

      :

      TRACE EVENT: WICED 43 - HFP AT cmd:AT+BTRH arg_type:4 arg:0 arg:?

      :

      TRACE EVENT: WICED 50 - [1]Sent AT response[0x01][ret 0][hndl 2]: ERROR

      :

      TRACE EVENT: WICED 43 - HFP AT cmd:AT+CLIP arg_type:2 arg:1 arg:1

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 2]: OK

      :

      TRACE EVENT: WICED 50 - HFP AT cmd:AT+BIA arg_type:2 arg:0 arg:1,1,1,0,0

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 2]: OK

      :

      Sending AG open 74:5C:4B:F6:16:A1:

      TRACE EVENT: WICED 36 - hci_control_proc_rx_cmd:6, 21e258

      :

      TRACE EVENT: WICED 18 - cmd_opcode 0xe03

      :

      TRACE EVENT: WICED 45 - hfp_ag_audio_open - state: 2  SCO inx: 0x00

      :

      TRACE EVENT: WICED 21 - send +BCS codec is 2:

      TRACE EVENT: WICED 52 - [1]Sent AT response[0x07][ret 0][hndl 2]: +BCS: 2

      :

      TRACE EVENT: WICED 42 - HFP AT cmd:AT+BCS arg_type:2 arg:2 arg:2

      :

      TRACE EVENT: WICED 47 - [1]Sent AT response[0x00][ret 0][hndl 2]: OK

      :

      TRACE EVENT: WICED 9 - Use WBS

      :

      TRACE EVENT: WICED 84 - hfp_ag_sco_create  is_orig: 1   sco_idx: 0x0  status:0x1fa4 retry_with_sco_only: 1

      :

      TRACE EVENT: WICED 36 - hci_control_management_callback 27

      :

      TRACE EVENT: WICED 39 - Got BTM_SCO_CONNECTED_EVT: sco_idx: 0

      :

      TRACE EVENT: WICED 59 - [1]hfp_ag_hci_send_ag_event: Sending Event: 3588  to UART

      :

      HCI_CONTROL_AG_EVENT_AUDIO_OPEN [Handle: 1]

      Wideband mode selected

      DEBUG (960): New state: BT_connection_state_AUDIO_CONNECTED

      DEBUG (689): BT_EVENT_AUDIO_CONNECTED

       

       

      Then I power cycle and start the module back up, here's log from startup to trying to reconnect:

      DEBUG (966): New state: BT_connection_state_MODULE_STARTUP

      device started

      startup write NVRAM, id: 16, len: 138

      TRACE EVENT: WICED 37 - hci_control_proc_rx_cmd:10, 21e27c

      :

      TRACE EVENT: WICED 17 - cmd_opcode 0x03

      :

      Command status: Success

      TRACE EVENT: WICED 36 - hci_control_proc_rx_cmd:6, 21e234

      :

      TRACE EVENT: WICED 17 - cmd_opcode 0x08

      :

      Command status: Success

      TRACE EVENT: WICED 36 - hci_control_proc_rx_cmd:5, 21e2a0

      :

      TRACE EVENT: WICED 17 - cmd_opcode 0x09

      :

      TRACE EVENT: WICED 32 -  Set the pairing allowed to 1

      :

      Command status: Success

      TRACE EVENT: WICED 38 - hci_control_proc_rx_cmd:144, 21f174

      :

      TRACE EVENT: WICED 17 - cmd_opcode 0x05

      :

      TRACE EVENT: WICED 41 - Updated Addr Resolution DB:8113. ID: 16

      :

      TRACE EVENT: WICED 6 - key: :

      TRACE EVENT: WICED 13 - 74 5c 4b f6 :

      TRACE EVENT: WICED 13 - 16 a1 04 76 :

      TRACE EVENT: WICED 13 - 03 3c a1 f6 :

      TRACE EVENT: WICED 13 - 28 ea c0 ac :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - c0 8f ce 62 :

      TRACE EVENT: WICED 13 - 44 17 28 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 04 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 13 - 00 00 00 00 :

      TRACE EVENT: WICED 7 - 00 00 :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 2 -

      :

      TRACE EVENT: WICED 44 - NVRAM write: 138 dev: [74 5c 4b f6 16 a1 ]

      :

      TRACE EVENT: WICED 36 - hci_control_proc_rx_cmd:4, 21e2c4

      :

      TRACE EVENT: WICED 19 - cmd_opcode 0xff02

      :

      Version 2.7, rev 0, build 18, Chip: 20706

      Device Fully Configured

      DEBUG (902): New state: BTECTINGction_state_CONNECTING

      DEBUG (698): BT_EVENT_CONNECTING

      Sending AG Connect 74:5C:4B:F6:16:A1:

      TRACE EVENT: WICED 37 - hci_control_proc_rx_cmd:10, 21e2c4

      :

      TRACE EVENT: WICED 18 - cmd_opcode 0xe01

      :

      TRACE EVENT: WICED 27 - advanced connection ret: 0:

      TRACE EVENT: WICED 36 - Security State: status: 0, flags: 0:

      TRACE EVENT: WICED 85 - Bonded Devices: status: 167, num: 1, addr_type: 33, addr: a9 05 0d 00 a0 e2  type: 0:

      TRACE EVENT: WICED 85 -   initiate service discovery app_handle = 1, uuid: 4382, num_attr: 4, p_scp: 21b120

      :

      TRACE EVENT: WICED 57 -   search attribute request,  74 5c 4b f6 16 a1 , 22c4ac

      :

      TRACE EVENT: WICED 54 - hci_control_ag_sdp_cback status:0xfff1, p_scb 21b120

      :

      TRACE EVENT: WICED 82 - [1]hfp_ag_rfcomm_start_server: rfcomm_create Res: 0x0  Port: 0x0005 UUID: 0x111e

      :

      TRACE EVENT: WICED 39 - hfp_ag_process_open_callback status=1

      :

      TRACE EVENT: WICED 39 - hfp_ag_process_open_callback: Try HSP

      :

      TRACE EVENT: WICED 85 -   initiate service discovery app_handle = 1, uuid: 4360, num_attr: 4, p_scp: 21b048

      :

      TRACE EVENT: WICED 57 -   search attribute request,  74 5c 4b f6 16 a1 , 22c3dc

      :

      TRACE EVENT: WICED 54 - hci_control_ag_sdp_cback status:0xfff1, p_scb 21b048

      :

      TRACE EVENT: WICED 82 - [1]hfp_ag_rfcomm_start_server: rfcomm_create Res: 0x0  Port: 0x0006 UUID: 0x1108

      :

      TRACE EVENT: WICED 39 - hfp_ag_process_open_callback status=1

      :

      TRACE EVENT: WICED 74 - [1]hfp_ag_rfcomm_start_server: rfcomm_create Port Already set to: 0x0006

      :

      TRACE EVENT: WICED 59 - [1]hfp_ag_hci_send_ag_event: Sending Event: 3585  to UART

        • 1. Re: BR/EDR Reconnect After Pairing
          JaPe_1712546

          Here is the btspy trace showing the issue:

           

          11:17:05.054 Sending AG Connect

          11:17:05.082 1 hci_control_proc_rx_cmd:10, 21e2c0

          11:17:05.082 1 cmd_opcode 0xe01

          11:17:05.082 1 Security State: status: 0, flags: 0

          11:17:05.082 1 Bonded Devices: status: 8103, num: 1, addr_type: 33, addr: 01 0e 00 00 67 82  type: 0

          11:17:05.101 1   initiate service discovery app_handle = 1, uuid: 4382, num_attr: 4, p_scp: 21b0a8

          11:17:05.101 1   search attribute request,  74 5c 4b f6 16 a1 , 22c724

          11:17:05.101 SENT [1] Command to HCI.  Name: HCI_Create_Connection   (Hex Code: 0x0405  Param Len: 13)

          11:17:05.101 HCI_RAW cmd Data:

          11:17:05.101     0000: a1 16 f6 4b 5c 74 18 cc 01 00 2a 83 01            ...K\t....*..  

          11:17:05.101                 BD_ADDR of remote device : 74-5c-4b-f6-16-a1

          11:17:05.101                   Packet Types Supported : 0xcc18 ( 2-DH1 3-DH1 DM1 DH1 2-DH3 3-DH3 DM3 DH3 2-DH5 3-DH5 DM5 DH5 )

          11:17:05.101                Page Scan Repetition Mode : 1 (0x01)

          11:17:05.101  Page Scan Mode(BT1.1) / Reserved(BT1.2) : 0 (0x00)

          11:17:05.101                             Clock Offset : 33578 (0x832a)

          11:17:05.101                        Allow Role Switch : 1 (0x01)

          11:17:05.101 HCI_RAW evt Data:

          11:17:05.101     0000: 0f 04 00 01                                       ....           

          11:17:05.101 RCVD [1] Event from HCI.  Name: HCI_Command_Status   (Hex Code: 0x0f  Param Len: 4)

          11:17:05.101                                   Status : Success (0x00)

          11:17:05.101                      Num HCI Cmd Packets : 1 (0x01)

          11:17:05.101                                 Cmd Code : 0x0405  (HCI_Create_Connection)

          11:17:06.205 HCI_RAW evt Data:

          11:17:06.205     0000: 03 0b 00 0c 00 a1 16 f6 4b 5c 74                  ........K\t    

          11:17:06.205 RCVD [1] Event from HCI.  Name: HCI_Connection_Complete   (Hex Code: 0x03  Param Len: 11)

          11:17:06.205                                   Status : Success (0x00)

          11:17:06.205                        Connection Handle : 12 (0x000c)

          11:17:06.205                        BD_ADDR of remote : 74-5c-4b-f6-16-a1

          11:17:06.205                                Link Type : 1 (0x01)

          11:17:06.205                          Encryption Mode : 0 (0x00)

          11:17:06.205 SENT [1] Command to HCI.  Name: HCI_Read_Remote_Version_Information   (Hex Code: 0x041d  Param Len: 2)

          11:17:06.205 HCI_RAW cmd Data:

          11:17:06.205     0000: 0c 00                                             ..             

          11:17:06.205                        Connection Handle : 12 (0x000c)

          11:17:06.205 L2CAP [0] SENT Command.  Name: L2C Information Request (0x0a) ID 0x02, len 2

          11:17:06.205                                 InfoType : Ext Features

          11:17:06.205 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 10  Data: 0x06 0x00 0x01 ...

          11:17:06.205 Data:

          11:17:06.205     0000: 06 00 01 00 0a 02 02 00 02 00                     ..........     

          11:17:06.205 HCI_RAW evt Data:

          11:17:06.205     0000: 0f 04 00 01                                       ....           

          11:17:06.205 RCVD [1] Event from HCI.  Name: HCI_Command_Status   (Hex Code: 0x0f  Param Len: 4)

          11:17:06.205                                   Status : Success (0x00)

          11:17:06.205                      Num HCI Cmd Packets : 1 (0x01)

          11:17:06.205                                 Cmd Code : 0x041d  (HCI_Read_Remote_Version_Information)

          11:17:06.227 SENT [1] Command to HCI.  Name: HCI_Read_Clock_Offset   (Hex Code: 0x041f  Param Len: 2)

          11:17:06.227 HCI_RAW cmd Data:

          11:17:06.227     0000: 0c 00                                             ..             

          11:17:06.227                        Connection Handle : 12 (0x000c)

          11:17:06.227 HCI_RAW evt Data:

          11:17:06.227     0000: 0c 08 00 0c 00 08 0a 00                           ........       

          11:17:06.227 RCVD [1] Event from HCI.  Name: HCI_Read_Remote_Version_Information_Complete   (Hex Code: 0x0c  Param Len: 8)

          11:17:06.227                                   Status : Success (0x00)

          11:17:06.227                        Connection Handle : 12 (0x000c)

          11:17:06.227                              LMP Version : 8 (0x08)

          11:17:06.227                        Manufacturer Name : 10 (0x000a) [CSR]

          11:17:06.227                           LMP Subversion : 12216 (0x2fb8)

          11:17:06.227 HCI_RAW evt Data:

          11:17:06.227     0000: 0f 04 00 01                                       ....           

          11:17:06.227 RCVD [1] Event from HCI.  Name: HCI_Command_Status   (Hex Code: 0x0f  Param Len: 4)

          11:17:06.227                                   Status : Success (0x00)

          11:17:06.227                      Num HCI Cmd Packets : 1 (0x01)

          11:17:06.227                                 Cmd Code : 0x041f  (HCI_Read_Clock_Offset)

          11:17:06.227 SENT [1] Command to HCI.  Name: HCI_Read_Authent_Payload_Tout   (Hex Code: 0x0c7b  Param Len: 2)

          11:17:06.227 HCI_RAW cmd Data:

          11:17:06.227     0000: 0c 00                                             ..             

          11:17:06.227                        Connection Handle : 12 (0x000c)

          11:17:06.227 HCI_RAW evt Data:

          11:17:06.227     0000: 20 07 a1 16 f6 4b 5c                               ....K\        

          11:17:06.227 RCVD [1] Event from HCI.  Name: HCI_Page_Scan_Repetition_Mode_Change   (Hex Code: 0x20  Param Len: 7)

          11:17:06.227                        BD_ADDR of remote : 74-5c-4b-f6-16-a1

          11:17:06.227                Page Scan Repetition Mode : 1 (0x01)

          11:17:06.227 HCI_RAW evt Data:

          11:17:06.227     0000: 1b 03 0c                                          ...            

          11:17:06.227 RCVD [1] Event from HCI.  Name: HCI_Max_Slots_Changed   (Hex Code: 0x1b  Param Len: 3)

          11:17:06.227                        Connection Handle : 12 (0x000c)

          11:17:06.227                            LMP Max Slots : 5 (0x05)

          11:17:06.227 HCI_RAW evt Data:

          11:17:06.227     0000: 0e 08 01 7b 0c 00 0c 00                           ........       

          11:17:06.227 RCVD [1] Event from HCI.  Name: HCI_Command_Complete   (Hex Code: 0x0e  Param Len: 8)

          11:17:06.227                      Num HCI Cmd Packets : 1 (0x01)

          11:17:06.227                                 Cmd Code : 0x0c7b  (HCI_Read_Authent_Payload_Tout)

          11:17:06.227                                   Status : Success (0x00)

          11:17:06.227                        Connection Handle : 12 (0x000c)

          11:17:06.227            Auth payload timeout (10msec) : 184 (0xb8)

          11:17:06.227 ERROR - parameter length wrong for the event code

          11:17:06.227 SENT [1] Command to HCI.  Name: HCI_Write_Link_Policy_Settings   (Hex Code: 0x080d  Param Len: 4)

          11:17:06.227 HCI_RAW cmd Data:

          11:17:06.227     0000: 0c 00 05 00                                       ....           

          11:17:06.227                        Connection Handle : 12 (0x000c)

          11:17:06.227                     Link Policy Settings : 0x0005

          11:17:06.227                                          : Enable Master Slave Switch

          11:17:06.227                                          : Enable Sniff Mode

          11:17:06.227 L2CAP [0] RECV Command.  Name: L2C Connection Request (0x02) ID: 0x01, Len: 4

          11:17:06.227                                      PSM : 0x1

          11:17:06.227                               Source CID : 0x380

          11:17:06.227 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 12  Data: 0x08 0x00 0x01 ...

          11:17:06.227 Data:

          11:17:06.227     0000: 08 00 01 00 02 01 04 00 01 00 80 03               ............   

          11:17:06.227 L2CAP [0] SENT Command.  Name: L2C Connection Response (0x03) ID 0x01, len 8

          11:17:06.227                          Destination CID : 0x4f

          11:17:06.227                               Source CID : 0x380

          11:17:06.227                                   Result : 0x0

          11:17:06.227                                   Status : 0x0

          11:17:06.227 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:06.227 Data:

          11:17:06.227     0000: 0c 00 01 00 03 01 08 00 4f 00 80 03 00 00 00 00   ........O.......

          11:17:06.227 L2CAP [0] SENT Command.  Name: L2C Configuration Request (0x04) ID 0x03, len 8

          11:17:06.228                          Destination CID : 896 (0x0380)

          11:17:06.228                     Flags (last request) : 0x0000

          11:17:06.228                               Option MTU :    64(0x0040)

          11:17:06.228 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:06.228 Data:

          11:17:06.228     0000: 0c 00 01 00 04 03 08 00 80 03 00 00 01 02 40 00   ..............@.

          11:17:06.228 HCI_RAW evt Data:

          11:17:06.228     0000: 0e 06 01 0d 08 00                                 ......         

          11:17:06.228 RCVD [1] Event from HCI.  Name: HCI_Command_Complete   (Hex Code: 0x0e  Param Len: 6)

          11:17:06.228                      Num HCI Cmd Packets : 1 (0x01)

          11:17:06.228                                 Cmd Code : 0x080d  (HCI_Write_Link_Policy_Settings)

          11:17:06.228                                   Status : Success (0x00)

          11:17:06.228                        Connection Handle : 12 (0x000c)

          11:17:06.228 SENT [1] Command to HCI.  Name: HCI_Write_Link_Supervision_Timeout   (Hex Code: 0x0c37  Param Len: 4)

          11:17:06.228 HCI_RAW cmd Data:

          11:17:06.228     0000: 0c 00 00 7d                                       ....           

          11:17:06.228          Connection/Physical Link Handle : 12 (0x000c)

          11:17:06.228                    Timeout (625us units) : 32000 (0x7d00)

          11:17:06.228 L2CAP [0] RECV Command.  Name: L2C Information Response (0x0b) ID: 0x02, Len: 8

          11:17:06.228                                 InfoType : Ext Features (2)

          11:17:06.228                                   Result : 0x0

          11:17:06.228                                Supported :

          11:17:06.228                                            Enhanced Retransmission Mode

          11:17:06.228                                            Streaming Mode

          11:17:06.228                                            No-FCS-Check Flag Supported

          11:17:06.228                                            Fixed Channels

          11:17:06.228 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:06.228 Data:

          11:17:06.228     0000: 0c 00 01 00 0b 02 08 00 02 00 00 00 b8 00 00 00   ................

          11:17:06.247 L2CAP [0] SENT Command.  Name: L2C Information Request (0x0a) ID 0x04, len 2

          11:17:06.247                                 InfoType : Fixed Channels

          11:17:06.247 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 10  Data: 0x06 0x00 0x01 ...

          11:17:06.247 Data:

          11:17:06.247     0000: 06 00 01 00 0a 04 02 00 03 00                     ..........     

          11:17:06.247 HCI_RAW evt Data:

          11:17:06.247     0000: 0e 06 01 37 0c 00                                 ...7..         

          11:17:06.247 RCVD [1] Event from HCI.  Name: HCI_Command_Complete   (Hex Code: 0x0e  Param Len: 6)

          11:17:06.247                      Num HCI Cmd Packets : 1 (0x01)

          11:17:06.247                                 Cmd Code : 0x0c37  (HCI_Write_Link_Supervision_Timeout)

          11:17:06.247                                   Status : Success (0x00)

          11:17:06.247                        Connection Handle : 12 (0x000c)

          11:17:06.247 HCI_RAW evt Data:

          11:17:06.247     0000: 13 05 01 0c 00                                    .....          

          11:17:06.247 RCVD [1] Event from HCI.  Name: HCI_Number_Of_Completed_Packets   (Hex Code: 0x13  Param Len: 5)

          11:17:06.247                                        0 : 12 (0x000c) - 2

          11:17:06.247 HCI_RAW evt Data:

          11:17:06.247     0000: 1c 05 00 0c 00                                    .....          

          11:17:06.247 RCVD [1] Event from HCI.  Name: HCI_Read_Clock_Offset_Complete   (Hex Code: 0x1c  Param Len: 5)

          11:17:06.247                                   Status : Success (0x00)

          11:17:06.247                        Connection Handle : 12 (0x000c)

          11:17:06.247                             Clock Offset : 812 (0x032c)

          11:17:06.247 L2CAP [0] RECV Command.  Name: L2C Configuration Request (0x04) ID: 0x02, Len: 8

          11:17:06.247                          Destination CID : 79 (0x004f)

          11:17:06.247                     Flags (last request) : 0x0000

          11:17:06.247                               Option MTU :    48(0x0030)

          11:17:06.247 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:06.247 Data:

          11:17:06.247     0000: 0c 00 01 00 04 02 08 00 4f 00 00 00 01 02 30 00   ........O.....0.

          11:17:06.247 L2CAP [0] SENT Command.  Name: L2C Configuration Response (0x05) ID 0x02, len 10

          11:17:06.247                               Source CID : 896 (0x0380)

          11:17:06.247                    Flags (last response) : 0x0000

          11:17:06.247                                   Result : 0 (0x0000)

          11:17:06.247                               Option MTU :    48(0x0030)

          11:17:06.247 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 18  Data: 0x0e 0x00 0x01 ...

          11:17:06.247 Data:

          11:17:06.247     0000: 0e 00 01 00 05 02 0a 00 80 03 00 00 00 00 01 02   ................

          11:17:06.247     0010: 30 00                                             0.             

          11:17:06.247 HCI_RAW evt Data:

          11:17:06.247     0000: 13 05 01 0c 00                                    .....          

          11:17:06.247 RCVD [1] Event from HCI.  Name: HCI_Number_Of_Completed_Packets   (Hex Code: 0x13  Param Len: 5)

          11:17:06.247                                        0 : 12 (0x000c) - 2

          11:17:06.247 L2CAP [0] RECV Command.  Name: L2C Configuration Response (0x05) ID: 0x03, Len: 10

          11:17:06.247                               Source CID : 79 (0x004f)

          11:17:06.247                    Flags (last response) : 0x0000

          11:17:06.247                                   Result : 0 (0x0000)

          11:17:06.247                               Option MTU :    64(0x0040)

          11:17:06.247 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 18  Data: 0x0e 0x00 0x01 ...

          11:17:06.247 Data:

          11:17:06.248     0000: 0e 00 01 00 05 03 0a 00 4f 00 00 00 00 00 01 02   ........O.......

          11:17:06.248     0010: 40 00                                             @.             

          11:17:06.248 L2CAP [0] RECV Command.  Name: L2C Information Response (0x0b) ID: 0x04, Len: 12

          11:17:06.248                                 InfoType : Fixed Channels (3)

          11:17:06.248                                   Result : 0x0

          11:17:06.248                                                   L2CAP Signaling channel supported

          11:17:06.248                                                  Connectionless reception supported

          11:17:06.248                                                       No AMP Manager Protocol Channel

          11:17:06.248                                                    ATT Protocol Channel (Supported)

          11:17:06.248                                                  ULP Signalling Channel (Supported)

          11:17:06.248                                                             SMP Channel (Unsupported)

          11:17:06.248 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 20  Data: 0x10 0x00 0x01 ...

          11:17:06.248 Data:

          11:17:06.248     0000: 10 00 01 00 0b 04 0c 00 03 00 00 00 36 00 00 00   ............6...

          11:17:06.248     0010: 00 00 00 00                                       ....           

          11:17:06.248 --

          11:17:06.248 Rcvd SDP message.  PDU Id: SERVICE_SEARCH_ATTR_REQ  (Hex: 0x06) Trans: 1 Param Len: 18

          11:17:06.248                               Descriptor : Data_Ele_Seq (0x06)

          11:17:06.248                              Length Code : 5 (In next byte)

          11:17:06.248                                   Length : 5

          11:17:06.248 Service Search Pattern:

          11:17:06.248       Sequence entry : 1.1

          11:17:06.248                               Descriptor : UUID (0x03)

          11:17:06.248                              Length Code : 2 (4 bytes)

          11:17:06.248                               UUID value : 65279 (0x0000feff)

          11:17:06.248                 Max Attribute Byte Count : 38 (0x0026)

          11:17:06.248 Requested Attribute ID List:

          11:17:06.248                               Descriptor : Data_Ele_Seq (0x06)

          11:17:06.248                              Length Code : 5 (In next byte)

          11:17:06.248                                   Length : 6

          11:17:06.248       Sequence entry : 1.1

          11:17:06.248                               Descriptor : Uint (0x01)

          11:17:06.248                              Length Code : 1 (2 bytes)

          11:17:06.248                               UINT value : 519 (0x0207)

          11:17:06.248       Sequence entry : 1.2

          11:17:06.248                               Descriptor : Uint (0x01)

          11:17:06.248                              Length Code : 1 (2 bytes)

          11:17:06.248                               UINT value : 524 (0x020c)

          11:17:06.248                   Continuation State Len : 0 (0x00)

          11:17:06.248 --

          11:17:06.248 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 27  Data: 0x17 0x00 0x4f ...

          11:17:06.248 Data:

          11:17:06.248     0000: 17 00 4f 00 06 00 01 00 12 35 05 1a 00 00 fe ff   ..O......5......

          11:17:06.248     0010: 00 26 35 06 09 02 07 09 02 0c 00                  .&5........    

          11:17:06.268 --

          11:17:06.268 Sent SDP message.  PDU Id: SERVICE_SEARCH_ATTR_RSP  (Hex: 0x07) Trans: 1 Param Len: 5

          11:17:06.268               Attribute Lists Byte Count : 2 (0x0002)

          11:17:06.268 Attribute Lists:

          11:17:06.268                               Descriptor : Data_Ele_Seq (0x06)

          11:17:06.268                              Length Code : 5 (In next byte)

          11:17:06.268                                   Length : 0

          11:17:06.268                   Continuation State Len : 0 (0x00)

          11:17:06.268 --

          11:17:06.268 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 14  Data: 0x0a 0x00 0x80 ...

          11:17:06.268 Data:

          11:17:06.268     0000: 0a 00 80 03 07 00 01 00 05 00 02 35 00 00         ...........5.. 

          11:17:06.268 HCI_RAW evt Data:

          11:17:06.268     0000: 13 05 01 0c 00                                    .....          

          11:17:06.268 RCVD [1] Event from HCI.  Name: HCI_Number_Of_Completed_Packets   (Hex Code: 0x13  Param Len: 5)

          11:17:06.268                                        0 : 12 (0x000c) - 2

          11:17:06.268 L2CAP [0] RECV Command.  Name: L2C Disconnection Request (0x06) ID: 0x03, Len: 4

          11:17:06.268                          Destination CID : 0x4f

          11:17:06.268                               Source CID : 0x380

          11:17:06.268 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 12  Data: 0x08 0x00 0x01 ...

          11:17:06.268 Data:

          11:17:06.268     0000: 08 00 01 00 06 03 04 00 4f 00 80 03               ........O...   

          11:17:06.268 L2CAP [0] SENT Command.  Name: L2C Disconnection Response (0x07) ID 0x03, len 4

          11:17:06.268                          Destination CID : 0x4f

          11:17:06.268                               Source CID : 0x380

          11:17:06.268 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 12  Data: 0x08 0x00 0x01 ...

          11:17:06.268 Data:

          11:17:06.268     0000: 08 00 01 00 07 03 04 00 4f 00 80 03               ........O...   

          11:17:06.268 L2CAP [0] RECV Command.  Name: L2C Connection Request (0x02) ID: 0x04, Len: 4

          11:17:06.268                                      PSM : 0x1

          11:17:06.268                               Source CID : 0x3c0

          11:17:06.268 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 12  Data: 0x08 0x00 0x01 ...

          11:17:06.268 Data:

          11:17:06.268     0000: 08 00 01 00 02 04 04 00 01 00 c0 03               ............   

          11:17:06.268 L2CAP [0] SENT Command.  Name: L2C Connection Response (0x03) ID 0x04, len 8

          11:17:06.268                          Destination CID : 0x50

          11:17:06.268                               Source CID : 0x3c0

          11:17:06.268                                   Result : 0x0

          11:17:06.268                                   Status : 0x0

          11:17:06.268 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:06.268 Data:

          11:17:06.268     0000: 0c 00 01 00 03 04 08 00 50 00 c0 03 00 00 00 00   ........P.......

          11:17:06.269 L2CAP [0] SENT Command.  Name: L2C Configuration Request (0x04) ID 0x05, len 8

          11:17:06.269                          Destination CID : 960 (0x03c0)

          11:17:06.269                     Flags (last request) : 0x0000

          11:17:06.269                               Option MTU :    64(0x0040)

          11:17:06.269 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:06.269 Data:

          11:17:06.269     0000: 0c 00 01 00 04 05 08 00 c0 03 00 00 01 02 40 00   ..............@.

          11:17:06.269 HCI_RAW evt Data:

          11:17:06.269     0000: 13 05 01 0c 00                                    .....          

          11:17:06.269 RCVD [1] Event from HCI.  Name: HCI_Number_Of_Completed_Packets   (Hex Code: 0x13  Param Len: 5)

          11:17:06.269                                        0 : 12 (0x000c) - 2

          11:17:06.269 L2CAP [0] RECV Command.  Name: L2C Configuration Request (0x04) ID: 0x05, Len: 8

          11:17:06.269                          Destination CID : 80 (0x0050)

          11:17:06.269                     Flags (last request) : 0x0000

          11:17:06.269                               Option MTU :    48(0x0030)

          11:17:06.269 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:06.269 Data:

          11:17:06.269     0000: 0c 00 01 00 04 05 08 00 50 00 00 00 01 02 30 00   ........P.....0.

          11:17:06.269 L2CAP [0] SENT Command.  Name: L2C Configuration Response (0x05) ID 0x05, len 10

          11:17:06.269                               Source CID : 960 (0x03c0)

          11:17:06.269                    Flags (last response) : 0x0000

          11:17:06.269                                   Result : 0 (0x0000)

          11:17:06.269                               Option MTU :    48(0x0030)

          11:17:06.269 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 18  Data: 0x0e 0x00 0x01 ...

          11:17:06.269 Data:

          11:17:06.269     0000: 0e 00 01 00 05 05 0a 00 c0 03 00 00 00 00 01 02   ................

          11:17:06.269     0010: 30 00                                             0.             

          11:17:06.285 L2CAP [0] RECV Command.  Name: L2C Configuration Response (0x05) ID: 0x05, Len: 10

          11:17:06.285                               Source CID : 80 (0x0050)

          11:17:06.285                    Flags (last response) : 0x0000

          11:17:06.285                                   Result : 0 (0x0000)

          11:17:06.285                               Option MTU :    64(0x0040)

          11:17:06.285 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 18  Data: 0x0e 0x00 0x01 ...

          11:17:06.285 Data:

          11:17:06.285     0000: 0e 00 01 00 05 05 0a 00 50 00 00 00 00 00 01 02   ........P.......

          11:17:06.285     0010: 40 00                                             @.             

          11:17:06.285 HCI_RAW evt Data:

          11:17:06.285     0000: 13 05 01 0c 00                                    .....          

          11:17:06.285 RCVD [1] Event from HCI.  Name: HCI_Number_Of_Completed_Packets   (Hex Code: 0x13  Param Len: 5)

          11:17:06.285                                        0 : 12 (0x000c) - 2

          11:17:06.285 --

          11:17:06.285 Rcvd SDP message.  PDU Id: SERVICE_SEARCH_ATTR_REQ  (Hex: 0x06) Trans: 1 Param Len: 30

          11:17:06.285                               Descriptor : Data_Ele_Seq (0x06)

          11:17:06.285                              Length Code : 5 (In next byte)

          11:17:06.285                                   Length : 5

          11:17:06.285 Service Search Pattern:

          11:17:06.285       Sequence entry : 1.1

          11:17:06.285                               Descriptor : UUID (0x03)

          11:17:06.285                              Length Code : 2 (4 bytes)

          11:17:06.285                               UUID value : 4608 (0x00001200)

          11:17:06.285                 Max Attribute Byte Count : 38 (0x0026)

          11:17:06.285 Requested Attribute ID List:

          11:17:06.285                               Descriptor : Data_Ele_Seq (0x06)

          11:17:06.285                              Length Code : 5 (In next byte)

          11:17:06.285                                   Length : 18

          11:17:06.285       Sequence entry : 1.1

          11:17:06.285                               Descriptor : Uint (0x01)

          11:17:06.285                              Length Code : 1 (2 bytes)

          11:17:06.285                               UINT value : 512 (0x0200) VERSION_NUMBER_LIST

          11:17:06.285       Sequence entry : 1.2

          11:17:06.285                               Descriptor : Uint (0x01)

          11:17:06.285                              Length Code : 1 (2 bytes)

          11:17:06.285                               UINT value : 513 (0x0201)

          11:17:06.285       Sequence entry : 1.3

          11:17:06.285                               Descriptor : Uint (0x01)

          11:17:06.285                              Length Code : 1 (2 bytes)

          11:17:06.285                               UINT value : 514 (0x0202)

          11:17:06.285       Sequence entry : 1.4

          11:17:06.285                               Descriptor : Uint (0x01)

          11:17:06.285                              Length Code : 1 (2 bytes)

          11:17:06.285                               UINT value : 515 (0x0203)

          11:17:06.285       Sequence entry : 1.5

          11:17:06.285                               Descriptor : Uint (0x01)

          11:17:06.285                              Length Code : 1 (2 bytes)

          11:17:06.285                               UINT value : 516 (0x0204)

          11:17:06.285       Sequence entry : 1.6

          11:17:06.285                               Descriptor : Uint (0x01)

          11:17:06.285                              Length Code : 1 (2 bytes)

          11:17:06.285                               UINT value : 517 (0x0205)

          11:17:06.285                   Continuation State Len : 0 (0x00)

          11:17:06.285 --

          11:17:06.285 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 39  Data: 0x23 0x00 0x50 ...

          11:17:06.285 Data:

          11:17:06.285     0000: 23 00 50 00 06 00 01 00 1e 35 05 1a 00 00 12 00   #.P......5......

          11:17:06.285     0010: 00 26 35 12 09 02 00 09 02 01 09 02 02 09 02 03   .&5.............

          11:17:06.285     0020: 09 02 04 09 02 05 00                              .......        

          11:17:06.285 --

          11:17:06.285 Sent SDP message.  PDU Id: SERVICE_SEARCH_ATTR_RSP  (Hex: 0x07) Trans: 1 Param Len: 5

          11:17:06.285               Attribute Lists Byte Count : 2 (0x0002)

          11:17:06.285 Attribute Lists:

          11:17:06.285                               Descriptor : Data_Ele_Seq (0x06)

          11:17:06.285                              Length Code : 5 (In next byte)

          11:17:06.285                                   Length : 0

          11:17:06.285                   Continuation State Len : 0 (0x00)

          11:17:06.285 --

          11:17:06.285 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 14  Data: 0x0a 0x00 0xc0 ...

          11:17:06.285 Data:

          11:17:06.285     0000: 0a 00 c0 03 07 00 01 00 05 00 02 35 00 00         ...........5.. 

          11:17:06.285 L2CAP [0] RECV Command.  Name: L2C Disconnection Request (0x06) ID: 0x06, Len: 4

          11:17:06.285                          Destination CID : 0x50

          11:17:06.285                               Source CID : 0x3c0

          11:17:06.285 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 12  Data: 0x08 0x00 0x01 ...

          11:17:06.285 Data:

          11:17:06.285     0000: 08 00 01 00 06 06 04 00 50 00 c0 03               ........P...   

          11:17:06.286 L2CAP [0] SENT Command.  Name: L2C Disconnection Response (0x07) ID 0x06, len 4

          11:17:06.286                          Destination CID : 0x50

          11:17:06.286                               Source CID : 0x3c0

          11:17:06.286 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 12  Data: 0x08 0x00 0x01 ...

          11:17:06.286 Data:

          11:17:06.286     0000: 08 00 01 00 07 06 04 00 50 00 c0 03               ........P...   

          11:17:06.286 HCI_RAW evt Data:

          11:17:06.286     0000: 13 05 01 0c 00                                    .....          

          11:17:06.286 RCVD [1] Event from HCI.  Name: HCI_Number_Of_Completed_Packets   (Hex Code: 0x13  Param Len: 5)

          11:17:06.286                                        0 : 12 (0x000c) - 2

          11:17:06.767 HCI_RAW evt Data:

          11:17:06.767     0000: 05 04 00 0c                                       ....           

          11:17:06.767 RCVD [1] Event from HCI.  Name: HCI_Disconnection_Complete   (Hex Code: 0x05  Param Len: 4)

          11:17:06.767                                   Status : Success (0x00)

          11:17:06.767                        Connection Handle : 12 (0x000c)

          11:17:06.767                                   Reason : 19 (0x13)

          11:17:06.767                                            Remote User Terminated Connection

          11:17:06.767 SENT [1] Command to HCI.  Name: HCI_Create_Connection   (Hex Code: 0x0405  Param Len: 13)

          11:17:06.767 HCI_RAW cmd Data:

          11:17:06.767     0000: a1 16 f6 4b 5c 74 18 cc 01 00 2c 83 01            ...K\t....,..  

          11:17:06.767                 BD_ADDR of remote device : 74-5c-4b-f6-16-a1

          11:17:06.767                   Packet Types Supported : 0xcc18 ( 2-DH1 3-DH1 DM1 DH1 2-DH3 3-DH3 DM3 DH3 2-DH5 3-DH5 DM5 DH5 )

          11:17:06.767                Page Scan Repetition Mode : 1 (0x01)

          11:17:06.767  Page Scan Mode(BT1.1) / Reserved(BT1.2) : 0 (0x00)

          11:17:06.767                             Clock Offset : 33580 (0x832c)

          11:17:06.767                        Allow Role Switch : 1 (0x01)

          11:17:06.767 HCI_RAW evt Data:

          11:17:06.767     0000: 0f 04 00 01                                       ....           

          11:17:06.767 RCVD [1] Event from HCI.  Name: HCI_Command_Status   (Hex Code: 0x0f  Param Len: 4)

          11:17:06.767                                   Status : Success (0x00)

          11:17:06.767                      Num HCI Cmd Packets : 1 (0x01)

          11:17:06.767                                 Cmd Code : 0x0405  (HCI_Create_Connection)

          11:17:07.853 HCI_RAW evt Data:

          11:17:07.853     0000: 03 0b 00 0c 00 a1 16 f6 4b 5c 74                  ........K\t    

          11:17:07.853 RCVD [1] Event from HCI.  Name: HCI_Connection_Complete   (Hex Code: 0x03  Param Len: 11)

          11:17:07.853                                   Status : Success (0x00)

          11:17:07.853                        Connection Handle : 12 (0x000c)

          11:17:07.853                        BD_ADDR of remote : 74-5c-4b-f6-16-a1

          11:17:07.853                                Link Type : 1 (0x01)

          11:17:07.853                          Encryption Mode : 0 (0x00)

          11:17:07.853 SENT [1] Command to HCI.  Name: HCI_Write_Link_Policy_Settings   (Hex Code: 0x080d  Param Len: 4)

          11:17:07.853 HCI_RAW cmd Data:

          11:17:07.853     0000: 0c 00 05 00                                       ....           

          11:17:07.853                        Connection Handle : 12 (0x000c)

          11:17:07.853                     Link Policy Settings : 0x0005

          11:17:07.853                                          : Enable Master Slave Switch

          11:17:07.853                                          : Enable Sniff Mode

          11:17:07.853 L2CAP [0] SENT Command.  Name: L2C Information Request (0x0a) ID 0x06, len 2

          11:17:07.853                                 InfoType : Ext Features

          11:17:07.853 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 10  Data: 0x06 0x00 0x01 ...

          11:17:07.853 Data:

          11:17:07.853     0000: 06 00 01 00 0a 06 02 00 02 00                     ..........     

          11:17:07.853 HCI_RAW evt Data:

          11:17:07.853     0000: 0e 06 01 0d 08 00                                 ......         

          11:17:07.853 RCVD [1] Event from HCI.  Name: HCI_Command_Complete   (Hex Code: 0x0e  Param Len: 6)

          11:17:07.853                      Num HCI Cmd Packets : 1 (0x01)

          11:17:07.853                                 Cmd Code : 0x080d  (HCI_Write_Link_Policy_Settings)

          11:17:07.853                                   Status : Success (0x00)

          11:17:07.853                        Connection Handle : 12 (0x000c)

          11:17:07.853 SENT [1] Command to HCI.  Name: HCI_Write_Link_Supervision_Timeout   (Hex Code: 0x0c37  Param Len: 4)

          11:17:07.853 HCI_RAW cmd Data:

          11:17:07.853     0000: 0c 00 00 7d                                       ....           

          11:17:07.854          Connection/Physical Link Handle : 12 (0x000c)

          11:17:07.854                    Timeout (625us units) : 32000 (0x7d00)

          11:17:07.854 HCI_RAW evt Data:

          11:17:07.854     0000: 0e 06 01 37 0c 00                                 ...7..         

          11:17:07.854 RCVD [1] Event from HCI.  Name: HCI_Command_Complete   (Hex Code: 0x0e  Param Len: 6)

          11:17:07.854                      Num HCI Cmd Packets : 1 (0x01)

          11:17:07.854                                 Cmd Code : 0x0c37  (HCI_Write_Link_Supervision_Timeout)

          11:17:07.854                                   Status : Success (0x00)

          11:17:07.854                        Connection Handle : 12 (0x000c)

          11:17:07.854 HCI_RAW evt Data:

          11:17:07.854     0000: 20 07 a1 16 f6 4b 5c                               ....K\        

          11:17:07.854 RCVD [1] Event from HCI.  Name: HCI_Page_Scan_Repetition_Mode_Change   (Hex Code: 0x20  Param Len: 7)

          11:17:07.854                        BD_ADDR of remote : 74-5c-4b-f6-16-a1

          11:17:07.854                Page Scan Repetition Mode : 1 (0x01)

          11:17:07.854 HCI_RAW evt Data:

          11:17:07.854     0000: 1b 03 0c                                          ...            

          11:17:07.854 RCVD [1] Event from HCI.  Name: HCI_Max_Slots_Changed   (Hex Code: 0x1b  Param Len: 3)

          11:17:07.854                        Connection Handle : 12 (0x000c)

          11:17:07.854                            LMP Max Slots : 5 (0x05)

          11:17:07.854 L2CAP [0] RECV Command.  Name: L2C Connection Request (0x02) ID: 0x01, Len: 4

          11:17:07.854                                      PSM : 0x1

          11:17:07.854                               Source CID : 0x400

          11:17:07.854 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 12  Data: 0x08 0x00 0x01 ...

          11:17:07.854 Data:

          11:17:07.854     0000: 08 00 01 00 02 01 04 00 01 00 00 04               ............   

          11:17:07.854 L2CAP [0] SENT Command.  Name: L2C Connection Response (0x03) ID 0x01, len 8

          11:17:07.854                          Destination CID : 0x51

          11:17:07.854                               Source CID : 0x400

          11:17:07.854                                   Result : 0x0

          11:17:07.854                                   Status : 0x0

          11:17:07.854 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:07.854 Data:

          11:17:07.854     0000: 0c 00 01 00 03 01 08 00 51 00 00 04 00 00 00 00   ........Q.......

          11:17:07.875 L2CAP [0] SENT Command.  Name: L2C Configuration Request (0x04) ID 0x07, len 8

          11:17:07.875                          Destination CID : 1024 (0x0400)

          11:17:07.875                     Flags (last request) : 0x0000

          11:17:07.875                               Option MTU :    64(0x0040)

          11:17:07.875 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:07.875 Data:

          11:17:07.875     0000: 0c 00 01 00 04 07 08 00 00 04 00 00 01 02 40 00   ..............@.

          11:17:07.875 HCI_RAW evt Data:

          11:17:07.875     0000: 13 05 01 0c 00                                    .....          

          11:17:07.875 RCVD [1] Event from HCI.  Name: HCI_Number_Of_Completed_Packets   (Hex Code: 0x13  Param Len: 5)

          11:17:07.875                                        0 : 12 (0x000c) - 2

          11:17:07.875 L2CAP [0] RECV Command.  Name: L2C Information Response (0x0b) ID: 0x06, Len: 8

          11:17:07.875                                 InfoType : Ext Features (2)

          11:17:07.875                                   Result : 0x0

          11:17:07.875                                Supported :

          11:17:07.875                                            Enhanced Retransmission Mode

          11:17:07.875                                            Streaming Mode

          11:17:07.875                                            No-FCS-Check Flag Supported

          11:17:07.875                                            Fixed Channels

          11:17:07.875 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:07.875 Data:

          11:17:07.875     0000: 0c 00 01 00 0b 06 08 00 02 00 00 00 b8 00 00 00   ................

          11:17:07.875 L2CAP [0] SENT Command.  Name: L2C Information Request (0x0a) ID 0x08, len 2

          11:17:07.875                                 InfoType : Fixed Channels

          11:17:07.875 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 10  Data: 0x06 0x00 0x01 ...

          11:17:07.875 Data:

          11:17:07.875     0000: 06 00 01 00 0a 08 02 00 03 00                     ..........     

          11:17:07.875 L2CAP [0] RECV Command.  Name: L2C Configuration Request (0x04) ID: 0x02, Len: 8

          11:17:07.875                          Destination CID : 81 (0x0051)

          11:17:07.875                     Flags (last request) : 0x0000

          11:17:07.875                               Option MTU :    48(0x0030)

          11:17:07.875 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:07.875 Data:

          11:17:07.875     0000: 0c 00 01 00 04 02 08 00 51 00 00 00 01 02 30 00   ........Q.....0.

          11:17:07.875 L2CAP [0] SENT Command.  Name: L2C Configuration Response (0x05) ID 0x02, len 10

          11:17:07.875                               Source CID : 1024 (0x0400)

          11:17:07.875                    Flags (last response) : 0x0000

          11:17:07.875                                   Result : 0 (0x0000)

          11:17:07.875                               Option MTU :    48(0x0030)

          11:17:07.875 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 18  Data: 0x0e 0x00 0x01 ...

          11:17:07.875 Data:

          11:17:07.875     0000: 0e 00 01 00 05 02 0a 00 00 04 00 00 00 00 01 02   ................

          11:17:07.875     0010: 30 00                                             0.             

          11:17:07.875 L2CAP [0] RECV Command.  Name: L2C Configuration Response (0x05) ID: 0x07, Len: 10

          11:17:07.875                               Source CID : 81 (0x0051)

          11:17:07.875                    Flags (last response) : 0x0000

          11:17:07.875                                   Result : 0 (0x0000)

          11:17:07.875                               Option MTU :    64(0x0040)

          11:17:07.875 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 18  Data: 0x0e 0x00 0x01 ...

          11:17:07.875 Data:

          11:17:07.875     0000: 0e 00 01 00 05 07 0a 00 51 00 00 00 00 00 01 02   ........Q.......

          11:17:07.875     0010: 40 00                                             @.             

          11:17:07.875 HCI_RAW evt Data:

          11:17:07.875     0000: 13 05 01 0c 00                                    .....          

          11:17:07.875 RCVD [1] Event from HCI.  Name: HCI_Number_Of_Completed_Packets   (Hex Code: 0x13  Param Len: 5)

          11:17:07.875                                        0 : 12 (0x000c) - 2

          11:17:07.875 L2CAP [0] RECV Command.  Name: L2C Information Response (0x0b) ID: 0x08, Len: 12

          11:17:07.875                                 InfoType : Fixed Channels (3)

          11:17:07.875                                   Result : 0x0

          11:17:07.875                                                   L2CAP Signaling channel supported

          11:17:07.875                                                  Connectionless reception supported

          11:17:07.875                                                       No AMP Manager Protocol Channel

          11:17:07.875                                                    ATT Protocol Channel (Supported)

          11:17:07.875                                                  ULP Signalling Channel (Supported)

          11:17:07.875                                                             SMP Channel (Unsupported)

          11:17:07.875 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 20  Data: 0x10 0x00 0x01 ...

          11:17:07.875 Data:

          11:17:07.875     0000: 10 00 01 00 0b 08 0c 00 03 00 00 00 36 00 00 00   ............6...

          11:17:07.875     0010: 00 00 00 00                                       ....           

          11:17:07.875 --

          11:17:07.875 Rcvd SDP message.  PDU Id: SERVICE_SEARCH_ATTR_REQ  (Hex: 0x06) Trans: 1 Param Len: 18

          11:17:07.875                               Descriptor : Data_Ele_Seq (0x06)

          11:17:07.875                              Length Code : 5 (In next byte)

          11:17:07.875                                   Length : 5

          11:17:07.875 Service Search Pattern:

          11:17:07.875       Sequence entry : 1.1

          11:17:07.875                               Descriptor : UUID (0x03)

          11:17:07.875                              Length Code : 2 (4 bytes)

          11:17:07.875                               UUID value : 65279 (0x0000feff)

          11:17:07.875                 Max Attribute Byte Count : 38 (0x0026)

          11:17:07.875 Requested Attribute ID List:

          11:17:07.876                               Descriptor : Data_Ele_Seq (0x06)

          11:17:07.876                              Length Code : 5 (In next byte)

          11:17:07.876                                   Length : 6

          11:17:07.876       Sequence entry : 1.1

          11:17:07.876                               Descriptor : Uint (0x01)

          11:17:07.876                              Length Code : 1 (2 bytes)

          11:17:07.876                               UINT value : 519 (0x0207)

          11:17:07.876       Sequence entry : 1.2

          11:17:07.876                               Descriptor : Uint (0x01)

          11:17:07.876                              Length Code : 1 (2 bytes)

          11:17:07.876                               UINT value : 524 (0x020c)

          11:17:07.876                   Continuation State Len : 0 (0x00)

          11:17:07.876 --

          11:17:07.876 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 27  Data: 0x17 0x00 0x51 ...

          11:17:07.876 Data:

          11:17:07.876     0000: 17 00 51 00 06 00 01 00 12 35 05 1a 00 00 fe ff   ..Q......5......

          11:17:07.876     0010: 00 26 35 06 09 02 07 09 02 0c 00                  .&5........    

          11:17:07.894 --

          11:17:07.894 Sent SDP message.  PDU Id: SERVICE_SEARCH_ATTR_RSP  (Hex: 0x07) Trans: 1 Param Len: 5

          11:17:07.894               Attribute Lists Byte Count : 2 (0x0002)

          11:17:07.894 Attribute Lists:

          11:17:07.894                               Descriptor : Data_Ele_Seq (0x06)

          11:17:07.894                              Length Code : 5 (In next byte)

          11:17:07.894                                   Length : 0

          11:17:07.894                   Continuation State Len : 0 (0x00)

          11:17:07.894 --

          11:17:07.894 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 14  Data: 0x0a 0x00 0x00 ...

          11:17:07.894 Data:

          11:17:07.894     0000: 0a 00 00 04 07 00 01 00 05 00 02 35 00 00         ...........5.. 

          11:17:07.894 HCI_RAW evt Data:

          11:17:07.894     0000: 13 05 01 0c 00                                    .....          

          11:17:07.894 RCVD [1] Event from HCI.  Name: HCI_Number_Of_Completed_Packets   (Hex Code: 0x13  Param Len: 5)

          11:17:07.894                                        0 : 12 (0x000c) - 2

          11:17:07.894 L2CAP [0] RECV Command.  Name: L2C Disconnection Request (0x06) ID: 0x03, Len: 4

          11:17:07.894                          Destination CID : 0x51

          11:17:07.894                               Source CID : 0x400

          11:17:07.894 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 12  Data: 0x08 0x00 0x01 ...

          11:17:07.894 Data:

          11:17:07.894     0000: 08 00 01 00 06 03 04 00 51 00 00 04               ........Q...   

          11:17:07.894 L2CAP [0] SENT Command.  Name: L2C Disconnection Response (0x07) ID 0x03, len 4

          11:17:07.894                          Destination CID : 0x51

          11:17:07.894                               Source CID : 0x400

          11:17:07.894 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 12  Data: 0x08 0x00 0x01 ...

          11:17:07.894 Data:

          11:17:07.894     0000: 08 00 01 00 07 03 04 00 51 00 00 04               ........Q...   

          11:17:07.894 L2CAP [0] RECV Command.  Name: L2C Connection Request (0x02) ID: 0x04, Len: 4

          11:17:07.894                                      PSM : 0x1

          11:17:07.894                               Source CID : 0x440

          11:17:07.894 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 12  Data: 0x08 0x00 0x01 ...

          11:17:07.894 Data:

          11:17:07.894     0000: 08 00 01 00 02 04 04 00 01 00 40 04               ..........@.   

          11:17:07.894 L2CAP [0] SENT Command.  Name: L2C Connection Response (0x03) ID 0x04, len 8

          11:17:07.894                          Destination CID : 0x40

          11:17:07.894                               Source CID : 0x440

          11:17:07.894                                   Result : 0x0

          11:17:07.894                                   Status : 0x0

          11:17:07.894 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:07.894 Data:

          11:17:07.894     0000: 0c 00 01 00 03 04 08 00 40 00 40 04 00 00 00 00   ........@.@.....

          11:17:07.894 L2CAP [0] SENT Command.  Name: L2C Configuration Request (0x04) ID 0x09, len 8

          11:17:07.894                          Destination CID : 1088 (0x0440)

          11:17:07.894                     Flags (last request) : 0x0000

          11:17:07.894                               Option MTU :    64(0x0040)

          11:17:07.894 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:07.894 Data:

          11:17:07.894     0000: 0c 00 01 00 04 09 08 00 40 04 00 00 01 02 40 00   ........@.....@.

          11:17:07.894 HCI_RAW evt Data:

          11:17:07.894     0000: 13 05 01 0c 00                                    .....          

          11:17:07.894 RCVD [1] Event from HCI.  Name: HCI_Number_Of_Completed_Packets   (Hex Code: 0x13  Param Len: 5)

          11:17:07.894                                        0 : 12 (0x000c) - 2

          11:17:07.894 L2CAP [0] RECV Command.  Name: L2C Configuration Request (0x04) ID: 0x05, Len: 8

          11:17:07.894                          Destination CID : 64 (0x0040)

          11:17:07.894                     Flags (last request) : 0x0000

          11:17:07.894                               Option MTU :    48(0x0030)

          11:17:07.894 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 16  Data: 0x0c 0x00 0x01 ...

          11:17:07.894 Data:

          11:17:07.895     0000: 0c 00 01 00 04 05 08 00 40 00 00 00 01 02 30 00   ........@.....0.

          11:17:07.895 L2CAP [0] SENT Command.  Name: L2C Configuration Response (0x05) ID 0x05, len 10

          11:17:07.895                               Source CID : 1088 (0x0440)

          11:17:07.895                    Flags (last response) : 0x0000

          11:17:07.895                                   Result : 0 (0x0000)

          11:17:07.895                               Option MTU :    48(0x0030)

          11:17:07.895 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 18  Data: 0x0e 0x00 0x01 ...

          11:17:07.895 Data:

          11:17:07.895     0000: 0e 00 01 00 05 05 0a 00 40 04 00 00 00 00 01 02   ........@.......

          11:17:07.895     0010: 30 00                                             0.             

          11:17:07.912 L2CAP [0] RECV Command.  Name: L2C Configuration Response (0x05) ID: 0x09, Len: 10

          11:17:07.912                               Source CID : 64 (0x0040)

          11:17:07.912                    Flags (last response) : 0x0000

          11:17:07.912                                   Result : 0 (0x0000)

          11:17:07.912                               Option MTU :    64(0x0040)

          11:17:07.912 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 18  Data: 0x0e 0x00 0x01 ...

          11:17:07.912 Data:

          11:17:07.912     0000: 0e 00 01 00 05 09 0a 00 40 00 00 00 00 00 01 02   ........@.......

          11:17:07.912     0010: 40 00                                             @.             

          11:17:07.912 HCI_RAW evt Data:

          11:17:07.913     0000: 13 05 01 0c 00                                    .....          

          11:17:07.913 RCVD [1] Event from HCI.  Name: HCI_Number_Of_Completed_Packets   (Hex Code: 0x13  Param Len: 5)

          11:17:07.913                                        0 : 12 (0x000c) - 2

          11:17:07.913 --

          11:17:07.913 Rcvd SDP message.  PDU Id: SERVICE_SEARCH_ATTR_REQ  (Hex: 0x06) Trans: 1 Param Len: 30

          11:17:07.913                               Descriptor : Data_Ele_Seq (0x06)

          11:17:07.913                              Length Code : 5 (In next byte)

          11:17:07.913                                   Length : 5

          11:17:07.913 Service Search Pattern:

          11:17:07.913       Sequence entry : 1.1

          11:17:07.913                               Descriptor : UUID (0x03)

          11:17:07.913                              Length Code : 2 (4 bytes)

          11:17:07.913                               UUID value : 4608 (0x00001200)

          11:17:07.913                 Max Attribute Byte Count : 38 (0x0026)

          11:17:07.913 Requested Attribute ID List:

          11:17:07.913                               Descriptor : Data_Ele_Seq (0x06)

          11:17:07.913                              Length Code : 5 (In next byte)

          11:17:07.913                                   Length : 18

          11:17:07.913       Sequence entry : 1.1

          11:17:07.913                               Descriptor : Uint (0x01)

          11:17:07.913                              Length Code : 1 (2 bytes)

          11:17:07.913                               UINT value : 512 (0x0200) VERSION_NUMBER_LIST

          11:17:07.913       Sequence entry : 1.2

          11:17:07.913                               Descriptor : Uint (0x01)

          11:17:07.913                              Length Code : 1 (2 bytes)

          11:17:07.913                               UINT value : 513 (0x0201)

          11:17:07.913       Sequence entry : 1.3

          11:17:07.913                               Descriptor : Uint (0x01)

          11:17:07.913                              Length Code : 1 (2 bytes)

          11:17:07.913                               UINT value : 514 (0x0202)

          11:17:07.913       Sequence entry : 1.4

          11:17:07.913                               Descriptor : Uint (0x01)

          11:17:07.913                              Length Code : 1 (2 bytes)

          11:17:07.913                               UINT value : 515 (0x0203)

          11:17:07.913       Sequence entry : 1.5

          11:17:07.913                               Descriptor : Uint (0x01)

          11:17:07.913                              Length Code : 1 (2 bytes)

          11:17:07.913                               UINT value : 516 (0x0204)

          11:17:07.913       Sequence entry : 1.6

          11:17:07.913                               Descriptor : Uint (0x01)

          11:17:07.913                              Length Code : 1 (2 bytes)

          11:17:07.913                               UINT value : 517 (0x0205)

          11:17:07.913                   Continuation State Len : 0 (0x00)

          11:17:07.913 --

          11:17:07.913 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 39  Data: 0x23 0x00 0x40 ...

          11:17:07.913 Data:

          11:17:07.913     0000: 23 00 40 00 06 00 01 00 1e 35 05 1a 00 00 12 00   #.@......5......

          11:17:07.913     0010: 00 26 35 12 09 02 00 09 02 01 09 02 02 09 02 03   .&5.............

          11:17:07.913     0020: 09 02 04 09 02 05 00                              .......        

          11:17:07.913 --

          11:17:07.913 Sent SDP message.  PDU Id: SERVICE_SEARCH_ATTR_RSP  (Hex: 0x07) Trans: 1 Param Len: 5

          11:17:07.913               Attribute Lists Byte Count : 2 (0x0002)

          11:17:07.913 Attribute Lists:

          11:17:07.913                               Descriptor : Data_Ele_Seq (0x06)

          11:17:07.913                              Length Code : 5 (In next byte)

          11:17:07.913                                   Length : 0

          11:17:07.913                   Continuation State Len : 0 (0x00)

          11:17:07.913 --

          11:17:07.913 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 14  Data: 0x0a 0x00 0x40 ...

          11:17:07.913 Data:

          11:17:07.913     0000: 0a 00 40 04 07 00 01 00 05 00 02 35 00 00         ..@........5.. 

          11:17:07.913 L2CAP [0] RECV Command.  Name: L2C Disconnection Request (0x06) ID: 0x06, Len: 4

          11:17:07.913                          Destination CID : 0x40

          11:17:07.913                               Source CID : 0x440

          11:17:07.913 RCVD [0] ACL Data from HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 12  Data: 0x08 0x00 0x01 ...

          11:17:07.913 Data:

          11:17:07.913     0000: 08 00 01 00 06 06 04 00 40 00 40 04               ........@.@.   

          11:17:07.913 L2CAP [0] SENT Command.  Name: L2C Disconnection Response (0x07) ID 0x06, len 4

          11:17:07.913                          Destination CID : 0x40

          11:17:07.913                               Source CID : 0x440

          11:17:07.913 SENT [0] ACL Data to HCI. Handle: 0x00c  Boundary: 2  Brdcst: 0  Len: 12  Data: 0x08 0x00 0x01 ...

          11:17:07.913 Data:

          11:17:07.913     0000: 08 00 01 00 07 06 04 00 40 00 40 04               ........@.@.   

          11:17:07.913 HCI_RAW evt Data:

          11:17:07.913     0000: 13 05 01 0c 00                                    .....          

          11:17:07.913 RCVD [1] Event from HCI.  Name: HCI_Number_Of_Completed_Packets   (Hex Code: 0x13  Param Len: 5)

          11:17:07.913                                        0 : 12 (0x000c) - 2

          11:17:08.416 HCI_RAW evt Data:

          11:17:08.416     0000: 05 04 00 0c                                       ....           

          11:17:08.416 RCVD [1] Event from HCI.  Name: HCI_Disconnection_Complete   (Hex Code: 0x05  Param Len: 4)

          11:17:08.416                                   Status : Success (0x00)

          11:17:08.416                        Connection Handle : 12 (0x000c)

          11:17:08.416                                   Reason : 19 (0x13)

          11:17:08.416                                            Remote User Terminated Connection

          11:17:08.416 1 hci_control_ag_sdp_cback status:0xfff1, p_scb 21b0a8

          11:17:08.416 1 [1]hfp_ag_rfcomm_start_server: rfcomm_create Res: 0x0  Port: 0x0007 UUID: 0x111e

          11:17:08.435 1 hfp_ag_process_open_callback status=1

          11:17:08.435 1 hfp_ag_process_open_callback: Try HSP

          11:17:09.420 1   initiate service discovery app_handle = 1, uuid: 4360, num_attr: 4, p_scp: 21b0a8

          11:17:09.420 1   search attribute request,  74 5c 4b f6 16 a1 , 22bcf8

          • 2. Re: BR/EDR Reconnect After Pairing
            JaPe_1712546

            The btspy trace is telling. The stack never initializes a search for the 4382 UUID. The code path is the same on reconnect as the initial connect, we call wiced_bt_sdp_init_discovery_db() and wiced_bt_sdp_service_search_attribute_request() with the exact same parameters. But for some reason in the reconnect case, the discovery database that the stack is using is incorrect. How could this be? Please support