Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

nRF SDK hci_usb sample disconnects after 40 seconds with extended connection via coded PHY #29780

Closed
kasapreyizz opened this issue Nov 4, 2020 · 9 comments
Assignees
Labels
area: Bluetooth bug The issue is a bug, or the PR is fixing a bug platform: nRF Nordic nRFx

Comments

@kasapreyizz
Copy link

Describe the bug
hci_usb sample with following configuration always disconnects after 40 seconds.

To Reproduce
Steps to reproduce the behavior:

  1. Build the hci_usb sample in v1.3.1 in nrf-sdk with following prj.conf
CONFIG_STDOUT_CONSOLE=y
CONFIG_GPIO=y
CONFIG_SERIAL=y
CONFIG_UART_INTERRUPT_DRIVEN=y

CONFIG_BT=y
CONFIG_BT_HCI=y

CONFIG_USB=y
CONFIG_USB_DEVICE_STACK=y
CONFIG_USB_DEVICE_BLUETOOTH=y
CONFIG_USB_DEVICE_BLUETOOTH_VS_H4=y

CONFIG_MAIN_STACK_SIZE=1024
CONFIG_SYSTEM_WORKQUEUE_STACK_SIZE=1024

CONFIG_BT_CTLR_RX_BUFFERS=18
CONFIG_BT_CTLR_TX_BUFFERS=19
CONFIG_BT_CTLR_TX_BUFFER_SIZE=251
CONFIG_BT_CTLR_DATA_LENGTH_MAX=251
CONFIG_BT_HCI_CMD_COUNT=20
CONFIG_BT_RX_BUF_COUNT=20
CONFIG_BT_RX_BUF_LEN=258
CONFIG_BT_DISCARDABLE_BUF_SIZE=257
CONFIG_BT_MAX_CONN=10
CONFIG_USB_REQUEST_BUFFER_SIZE=384

CONFIG_BT_EXT_ADV=y
CONFIG_BT_CTLR_PHY_CODED=y
CONFIG_BT_LLL_VENDOR_NORDIC=y
CONFIG_BT_LL_NRFXLIB=y
  1. Connect the nrf52840-dongle flashed with following code to a linux host (my host is Rpi3 with kernel version 5.4.51-v7+

  2. Command to make an extended connection via hcitool which is similar to: hcitool cmd 0x08 0x0043 0x00 0x01 0x00 0x00 0xSS 0xRE 0xDD 0xCA 0xMA 0x04 0x0000 0x0001 0x0000 0x0001 0x0006 0x0000 0x18 0x00 0x00 0x00 0x80 0x0C 0x00 0x00 0x00 0x0047

Expected behavior
After commanding, following btmon output is taken and client device is indicating that an connection has been established.

< HCI Command: LE Extended C.. (0x08|0x0043) plen 26  #2798 [hci0] 12808.706224
        Filter policy: White list is not used (0x00)
        Own address type: Random (0x01)
        Peer address type: Public (0x00)
        Peer address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Initiating PHYs: 0x04
        Entry 0: LE Coded
          Scan interval: 160.000 msec (0x0100)
          Scan window: 160.000 msec (0x0100)
          Min connection interval: 7.50 msec (0x0006)
          Max connection interval: 30.00 msec (0x0018)
          Connection latency: 0 (0x0000)
          Supervision timeout: 32000 msec (0x0c80)
          Min connection length: 0.000 msec (0x0000)
          Max connection length: 11360.000 msec (0x4700)
> HCI Event: Command Status (0x0f) plen 4             #2799 [hci0] 12808.707731
      LE Extended Create Connection (0x08|0x0043) ncmd 1
        Status: Success (0x00)
< HCI Command: Disconnect (0x01|0x0006) plen 3        #2800 [hci0] 12808.707963
        Handle: 0
        Reason: Remote User Terminated Connection (0x13)
@ RAW Close: hcitool                               {0x0003} [hci0] 12808.708096
> HCI Event: Command Status (0x0f) plen 4             #2801 [hci0] 12808.708754
      Disconnect (0x01|0x0006) ncmd 1
        Status: Success (0x00)
> HCI Event: LE Meta Event (0x3e) plen 31             #2802 [hci0] 12808.737731
      LE Enhanced Connection Complete (0x0a)
        Status: Success (0x00)
        Handle: 1
        Role: Master (0x00)
        Peer address type: Public (0x00)
        Peer address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Local resolvable private address: 00:00:00:00:00:00 (Non-Resolvable)
        Peer resolvable private address: 00:00:00:00:00:00 (Non-Resolvable)
        Connection interval: 30.00 msec (0x0018)
        Connection latency: 0 (0x0000)
        Supervision timeout: 32000 msec (0x0c80)
        Master clock accuracy: 0x00
@ MGMT Event: Device Connected (0x000b) plen 13    {0x0002} [hci0] 12808.737815
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Flags: 0x00000000
        Data length: 0
@ MGMT Event: Device Connected (0x000b) plen 13    {0x0001} [hci0] 12808.737815
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Flags: 0x00000000
        Data length: 0
< HCI Command: LE Read Remote.. (0x08|0x0016) plen 2  #2803 [hci0] 12808.738130
        Handle: 1
= bluetoothd: Device 00:85:C0:06:F8:9B is already connected        12808.742502
= bluetoothd: Device is already marked as connected         [hci0] 12808.742592
> HCI Event: LE Meta Event (0x3e) plen 4              #2804 [hci0] 12808.738751
      LE Channel Selection Algorithm (0x14)
        Handle: 1
        Algorithm: #2 (0x01)
> HCI Event: Command Status (0x0f) plen 4             #2805 [hci0] 12808.739766
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
> HCI Event: LE Meta Event (0x3e) plen 12             #2806 [hci0] 12808.810746
      LE Read Remote Used Features (0x04)
        Status: Success (0x00)
        Handle: 1
        Features: 0x25 0x49 0x00 0x00 0x00 0x00 0x00 0x00
          LE Encryption
          Extended Reject Indication
          LE Data Packet Length Extension
          LE 2M PHY
          LE Coded PHY
          Channel Selection Algorithm #2
= bluetoothd: No cache for 00:85:C0:06:F8:9B                       12808.811727
< ACL Data TX: Handle 1 flags 0x00 dlen 7             #2807 [hci0] 12808.812007
      ATT: Exchange MTU Request (0x02) len 2
        Client RX MTU: 517

But after approximately 40 seconds (not exact, changes slightly every time), it's disconnects. Following btmon output is shows that.

> HCI Event: Disconnect Complete (0x05) plen 4        #2809 [hci0] 12837.631685
        Status: Success (0x00)
        Handle: 0
        Reason: Connection Timeout (0x08)
@ MGMT Event: Device Disconnected (0x000c) plen 8  {0x0002} [hci0] 12837.631741
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Reason: Connection timeout (0x01)
@ MGMT Event: Device Disconnected (0x000c) plen 8  {0x0001} [hci0] 12837.631741
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Reason: Connection timeout (0x01)
< HCI Command: Disconnect (0x01|0x0006) plen 3        #2810 [hci0] 12839.696163
        Handle: 1
        Reason: Remote User Terminated Connection (0x13)
> HCI Event: Command Status (0x0f) plen 4             #2811 [hci0] 12839.698692
      Disconnect (0x01|0x0006) ncmd 1
        Status: Success (0x00)
> HCI Event: Disconnect Complete (0x05) plen 4        #2812 [hci0] 12839.739683
        Status: Success (0x00)
        Handle: 1
        Reason: Connection Terminated By Local Host (0x16)
@ MGMT Event: Device Disconnected (0x000c) plen 8  {0x0002} [hci0] 12839.739744
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Reason: Connection terminated by local host (0x02)
@ MGMT Event: Device Disconnected (0x000c) plen 8  {0x0001} [hci0] 12839.739744
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Reason: Connection terminated by local host (0x02)

My project supports IPSP and when I connect them to 6lowpan_core via debugfs, following logs are taken:

< HCI Command: LE Extended C.. (0x08|0x0043) plen 26  #3010 [hci0] 78934.236283
        Filter policy: White list is not used (0x00)
        Own address type: Random (0x01)
        Peer address type: Public (0x00)
        Peer address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Initiating PHYs: 0x04
        Entry 0: LE Coded
          Scan interval: 160.000 msec (0x0100)
          Scan window: 160.000 msec (0x0100)
          Min connection interval: 7.50 msec (0x0006)
          Max connection interval: 30.00 msec (0x0018)
          Connection latency: 0 (0x0000)
          Supervision timeout: 32000 msec (0x0c80)
          Min connection length: 0.000 msec (0x0000)
          Max connection length: 11360.000 msec (0x4700)
> HCI Event: Command Status (0x0f) plen 4             #3011 [hci0] 78934.238079
      LE Extended Create Connection (0x08|0x0043) ncmd 1
        Status: Success (0x00)
@ RAW Close: hcitool                               {0x0003} [hci0] 78934.238245
> HCI Event: LE Meta Event (0x3e) plen 31             #3012 [hci0] 78934.259078
      LE Enhanced Connection Complete (0x0a)
        Status: Success (0x00)
        Handle: 0
        Role: Master (0x00)
        Peer address type: Public (0x00)
        Peer address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Local resolvable private address: 00:00:00:00:00:00 (Non-Resolvable)
        Peer resolvable private address: 00:00:00:00:00:00 (Non-Resolvable)
        Connection interval: 30.00 msec (0x0018)
        Connection latency: 0 (0x0000)
        Supervision timeout: 32000 msec (0x0c80)
        Master clock accuracy: 0x00
@ MGMT Event: Device Connected (0x000b) plen 13    {0x0002} [hci0] 78934.259156
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Flags: 0x00000000
        Data length: 0
@ MGMT Event: Device Connected (0x000b) plen 13    {0x0001} [hci0] 78934.259156
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Flags: 0x00000000
        Data length: 0
< HCI Command: LE Read Remote.. (0x08|0x0016) plen 2  #3013 [hci0] 78934.259467
        Handle: 0
> HCI Event: LE Meta Event (0x3e) plen 4              #3014 [hci0] 78934.260093
      LE Channel Selection Algorithm (0x14)
        Handle: 0
        Algorithm: #2 (0x01)
> HCI Event: Command Status (0x0f) plen 4             #3015 [hci0] 78934.261106
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
> HCI Event: LE Meta Event (0x3e) plen 12             #3016 [hci0] 78934.321107
      LE Read Remote Used Features (0x04)
        Status: Success (0x00)
        Handle: 0
        Features: 0x25 0x49 0x00 0x00 0x00 0x00 0x00 0x00
          LE Encryption
          Extended Reject Indication
          LE Data Packet Length Extension
          LE 2M PHY
          LE Coded PHY
          Channel Selection Algorithm #2
= bluetoothd: No cache for 00:85:C0:06:F8:9B                       78934.325316
< ACL Data TX: Handle 0 flags 0x00 dlen 7             #3017 [hci0] 78934.322358
      ATT: Exchange MTU Request (0x02) len 2
        Client RX MTU: 517
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3018 [hci0] 78934.381102
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 18            #3019 [hci0] 78936.280126
      LE L2CAP: LE Connection Request (0x14) ident 1 len 10
        PSM: 35 (0x0023)
        Source CID: 64
        MTU: 1280
        MPS: 247
        Credits: 6
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3020 [hci0] 78936.301074
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 18            #3021 [hci0] 78936.331078
      LE L2CAP: LE Connection Response (0x15) ident 1 len 10
        Destination CID: 64
        MTU: 1280
        MPS: 50
        Credits: 0
        Result: Connection successful (0x0000)
> ACL Data RX: Handle 0 flags 0x02 dlen 27            #3022 [hci0] 78936.332468
> ACL Data RX: Handle 0 flags 0x01 dlen 12            #3023 [hci0] 78936.333830
      Channel: 64 len 35 sdu 33 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        21 00 7b 49 3a 02 01 ff 06 f8 9b 87 00 c9 5d 00  !.{I:.........].
        00 00 00 fe 80 00 00 00 00 00 00 02 85 c0 ff fe  ................
        06 f8 9b                                         ...             
< ACL Data TX: Handle 0 flags 0x00 dlen 12            #3024 [hci0] 78936.333941
      LE L2CAP: LE Flow Control Credit (0x16) ident 2 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3025 [hci0] 78936.335197
      LE L2CAP: LE Flow Control Credit (0x16) ident 3 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3026 [hci0] 78936.356380
      Channel: 64 len 50 sdu 60 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3c 00 79 4b 00 16 3a 00 05 02 00 00 01 00 8f 00  <.yK..:.........
        88 05 00 00 00 02 04 00 00 00 ff 02 00 00 00 00  ................
        00 00 00 00 00 01 ff 37 e4 33 04 00 00 00 ff 02  .......7.3......
        00 00                                            ..              
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3027 [hci0] 78936.361582
      LE L2CAP: LE Flow Control Credit (0x16) ident 5 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 16            #3028 [hci0] 78936.361724
      Channel: 64 len 12 sdu 12 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 00 00 00 00 02              ............    
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3029 [hci0] 78936.362074
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3030 [hci0] 78936.362949
      LE L2CAP: LE Flow Control Credit (0x16) ident 7 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3031 [hci0] 78936.364331
      LE L2CAP: LE Flow Control Credit (0x16) ident 9 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3032 [hci0] 78936.365074
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 18            #3033 [hci0] 78936.365702
      Channel: 64 len 14 sdu 12 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        0c 00 7b 4b 3a 02 85 00 7b b8 00 00 00 00        ..{K:...{.....  
< ACL Data TX: Handle 0 flags 0x00 dlen 12            #3034 [hci0] 78936.365810
      LE L2CAP: LE Flow Control Credit (0x16) ident 3 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3035 [hci0] 78936.391088
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3036 [hci0] 78936.392078
      LE L2CAP: LE Flow Control Credit (0x16) ident 11 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3037 [hci0] 78936.393067
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3038 [hci0] 78936.393446
      LE L2CAP: LE Flow Control Credit (0x16) ident 13 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3039 [hci0] 78936.436643
      Channel: 64 len 50 sdu 60 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3c 00 79 4b 00 16 3a 00 05 02 00 00 01 00 8f 00  <.yK..:.........
        8c 05 00 00 00 02 02 00 00 00 ff 02 00 00 00 00  ................
        00 00 00 00 00 01 ff 37 e4 33 02 00 00 00 ff 02  .......7.3......
        00 00                                            ..              
< ACL Data TX: Handle 0 flags 0x00 dlen 16            #3040 [hci0] 78936.436734
      Channel: 64 len 12 sdu 12 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 00 00 00 00 02              ............    
> ACL Data RX: Handle 0 flags 0x02 dlen 27            #3041 [hci0] 78936.451099
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3042 [hci0] 78936.452071
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x01 dlen 7             #3043 [hci0] 78936.453698
      Channel: 64 len 30 sdu 28 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        1c 00 7b 3b 3a 02 85 00 fc d4 00 00 00 00 01 02  ..{;:...........
        00 85 c0 ff fe 06 05 9b 00 00 00 00 00 02        ..............  
< ACL Data TX: Handle 0 flags 0x00 dlen 12            #3044 [hci0] 78936.453922
      LE L2CAP: LE Flow Control Credit (0x16) ident 4 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3045 [hci0] 78936.454072
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3046 [hci0] 78936.455075
      LE L2CAP: LE Flow Control Credit (0x16) ident 15 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3047 [hci0] 78936.481084
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3048 [hci0] 78936.481447
      LE L2CAP: LE Flow Control Credit (0x16) ident 17 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3049 [hci0] 78936.976269
      Channel: 64 len 50 sdu 60 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3c 00 79 4b 00 16 3a 00 05 02 00 00 01 00 8f 00  <.yK..:.........
        88 05 00 00 00 02 04 00 00 00 ff 02 00 00 00 00  ................
        00 00 00 00 00 01 ff 37 e4 33 04 00 00 00 ff 02  .......7.3......
        00 00                                            ..              
< ACL Data TX: Handle 0 flags 0x00 dlen 16            #3050 [hci0] 78936.976325
      Channel: 64 len 12 sdu 12 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 00 00 00 00 02              ............    
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3051 [hci0] 78936.992070
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3052 [hci0] 78936.993066
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3053 [hci0] 78936.994075
      LE L2CAP: LE Flow Control Credit (0x16) ident 19 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3054 [hci0] 78937.021695
      LE L2CAP: LE Flow Control Credit (0x16) ident 21 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 47            #3055 [hci0] 78937.056308
      Channel: 64 len 43 sdu 41 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        29 00 7b 49 3a 02 01 ff 37 e4 33 87 00 7c d8 00  ).{I:...7.3..|..
        00 00 00 fe 80 00 00 00 00 00 00 f3 a0 b4 ff fe  ................
        37 e4 33 0e 01 76 47 07 46 04 41                 7.3..vG.F.A     
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3056 [hci0] 78937.082066
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3057 [hci0] 78937.111855
      LE L2CAP: LE Flow Control Credit (0x16) ident 23 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3058 [hci0] 78938.096540
      Channel: 64 len 50 sdu 80 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        50 00 79 3b 00 16 3a 00 05 02 00 00 01 00 8f 00  P.y;..:.........
        fc 5d 00 00 00 03 04 00 00 00 ff 02 00 00 00 00  .]..............
        00 00 00 00 00 01 ff 00 00 00 04 00 00 00 ff 02  ................
        00 00                                            ..              
< ACL Data TX: Handle 0 flags 0x00 dlen 36            #3059 [hci0] 78938.096601
      Channel: 64 len 32 sdu 32 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 01 ff 37 e4 33 04 00 00 00  .........7.3....
        ff 02 00 00 00 00 00 00 00 00 00 00 00 00 00 02  ................
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3060 [hci0] 78938.126296
      Channel: 64 len 50 sdu 60 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3c 00 79 3b 00 16 3a 00 05 02 00 00 01 00 8f 00  <.y;..:.........
        e1 e9 00 00 00 02 04 00 00 00 ff 02 00 00 00 00  ................
        00 00 00 00 00 00 00 00 00 fb 04 00 00 00 ff 02  ................
        00 00                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3061 [hci0] 78938.131068
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 16            #3062 [hci0] 78938.131146
      Channel: 64 len 12 sdu 12 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 01 ff 00 00 00              ............    
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3063 [hci0] 78938.133191
      LE L2CAP: LE Flow Control Credit (0x16) ident 25 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3064 [hci0] 78938.134061
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3065 [hci0] 78938.134566
      LE L2CAP: LE Flow Control Credit (0x16) ident 27 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3066 [hci0] 78938.161064
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3067 [hci0] 78938.162062
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3068 [hci0] 78938.163440
      LE L2CAP: LE Flow Control Credit (0x16) ident 29 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3069 [hci0] 78938.166270
      Channel: 64 len 50 sdu 60 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3c 00 79 3b 00 16 3a 00 05 02 00 00 01 00 8f 00  <.y;..:.........
        e1 e9 00 00 00 02 04 00 00 00 ff 02 00 00 00 00  ................
        00 00 00 00 00 00 00 00 00 fb 04 00 00 00 ff 02  ................
        00 00                                            ..              
< ACL Data TX: Handle 0 flags 0x00 dlen 16            #3070 [hci0] 78938.166306
      Channel: 64 len 12 sdu 12 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 01 ff 00 00 00              ............    
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3071 [hci0] 78938.191071
      LE L2CAP: LE Flow Control Credit (0x16) ident 31 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3072 [hci0] 78938.192063
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3073 [hci0] 78938.193061
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3074 [hci0] 78938.193691
      LE L2CAP: LE Flow Control Credit (0x16) ident 33 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3075 [hci0] 78938.221318
      LE L2CAP: LE Flow Control Credit (0x16) ident 35 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3076 [hci0] 78938.394358
      Channel: 64 len 50 sdu 168 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        a8 00 6f 3b 09 4c d9 fb f0 14 e9 14 e9 5e fd 00  ..o;.L.......^..
        00 00 00 00 02 00 00 00 02 00 00 01 33 01 33 01  ............3.3.
        34 01 65 01 37 01 33 01 65 01 66 01 66 01 66 01  4.e.7.3.e.f.f.f.
        34 01                                            4.              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3077 [hci0] 78938.394410
      Channel: 64 len 50 sdu 120 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        62 01 30 01 61 01 33 01 66 01 30 01 30 01 30 01  b.0.a.3.f.0.0.0.
        30 01 30 01 30 01 30 01 30 01 30 01 30 01 30 01  0.0.0.0.0.0.0.0.
        30 01 30 01 38 01 65 01 66 03 69 70 36 04 61 72  0.0.8.e.f.ip6.ar
        70 61                                            pa              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3078 [hci0] 78938.394434
      Channel: 64 len 50 sdu 70 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 ff 00 01 0b 72 61 73 70 62 65 72 72 79 70  ......raspberryp
        69 05 6c 6f 63 61 6c 00 00 ff 00 01 c0 5a 00 1c  i.local......Z..
        00 01 00 00 00 78 00 10 fe 80 00 00 00 00 00 00  .....x..........
        f3 a0                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3079 [hci0] 78938.402065
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 24            #3080 [hci0] 78938.402143
      Channel: 64 len 20 sdu 20 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        b4 ff fe 37 e4 33 c0 0c 00 0c 00 01 00 00 00 78  ...7.3.........x
        00 02 c0 5a                                      ...Z            
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3081 [hci0] 78938.402692
      LE L2CAP: LE Flow Control Credit (0x16) ident 37 len 4
        Source CID: 64
        Credits: 3
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3082 [hci0] 78938.432062
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3083 [hci0] 78938.433061
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3084 [hci0] 78938.461062
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3085 [hci0] 78938.491565
      LE L2CAP: LE Flow Control Credit (0x16) ident 39 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3086 [hci0] 78938.645005
      Channel: 64 len 50 sdu 168 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        a8 00 6f 3b 09 4c d9 fb f0 14 e9 14 e9 5e fd 00  ..o;.L.......^..
        00 00 00 00 02 00 00 00 02 00 00 01 33 01 33 01  ............3.3.
        34 01 65 01 37 01 33 01 65 01 66 01 66 01 66 01  4.e.7.3.e.f.f.f.
        34 01                                            4.              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3087 [hci0] 78938.645051
      Channel: 64 len 50 sdu 120 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        62 01 30 01 61 01 33 01 66 01 30 01 30 01 30 01  b.0.a.3.f.0.0.0.
        30 01 30 01 30 01 30 01 30 01 30 01 30 01 30 01  0.0.0.0.0.0.0.0.
        30 01 30 01 38 01 65 01 66 03 69 70 36 04 61 72  0.0.8.e.f.ip6.ar
        70 61                                            pa              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3088 [hci0] 78938.645077
      Channel: 64 len 50 sdu 70 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 ff 00 01 0b 72 61 73 70 62 65 72 72 79 70  ......raspberryp
        69 05 6c 6f 63 61 6c 00 00 ff 00 01 c0 5a 00 1c  i.local......Z..
        00 01 00 00 00 78 00 10 fe 80 00 00 00 00 00 00  .....x..........
        f3 a0                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3089 [hci0] 78938.672065
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 24            #3090 [hci0] 78938.672159
      Channel: 64 len 20 sdu 20 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        b4 ff fe 37 e4 33 c0 0c 00 0c 00 01 00 00 00 78  ...7.3.........x
        00 02 c0 5a                                      ...Z            
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3091 [hci0] 78938.672941
      LE L2CAP: LE Flow Control Credit (0x16) ident 41 len 4
        Source CID: 64
        Credits: 3
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3092 [hci0] 78938.701064
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3093 [hci0] 78938.701140
      Channel: 64 len 50 sdu 100 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        64 00 79 3b 00 16 3a 00 05 02 00 00 01 00 8f 00  d.y;..:.........
        f8 4a 00 00 00 04 04 00 00 00 ff 02 00 00 00 00  .J..............
        00 00 00 00 00 00 00 00 00 fb 04 00 00 00 ff 02  ................
        00 00                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3094 [hci0] 78938.702061
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3095 [hci0] 78938.702122
      Channel: 64 len 50 sdu 52 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 01 ff 00 00 00 04 00 00 00  ................
        ff 02 00 00 00 00 00 00 00 00 00 01 ff 37 e4 33  .............7.3
        04 00 00 00 ff 02 00 00 00 00 00 00 00 00 00 00  ................
        00 00                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3096 [hci0] 78938.703064
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 6             #3097 [hci0] 78938.703122
      Channel: 64 len 2 sdu 2 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 02                                            ..              
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3098 [hci0] 78938.731815
      LE L2CAP: LE Flow Control Credit (0x16) ident 43 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3099 [hci0] 78938.733060
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3100 [hci0] 78938.734438
      LE L2CAP: LE Flow Control Credit (0x16) ident 45 len 4
        Source CID: 64
        Credits: 2
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3101 [hci0] 78938.761062
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3102 [hci0] 78938.762064
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3103 [hci0] 78938.790813
      LE L2CAP: LE Flow Control Credit (0x16) ident 47 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3104 [hci0] 78938.895728
      Channel: 64 len 50 sdu 168 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        a8 00 6f 3b 09 4c d9 fb f0 14 e9 14 e9 5e fd 00  ..o;.L.......^..
        00 00 00 00 02 00 00 00 02 00 00 01 33 01 33 01  ............3.3.
        34 01 65 01 37 01 33 01 65 01 66 01 66 01 66 01  4.e.7.3.e.f.f.f.
        34 01                                            4.              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3105 [hci0] 78938.895772
      Channel: 64 len 50 sdu 120 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        62 01 30 01 61 01 33 01 66 01 30 01 30 01 30 01  b.0.a.3.f.0.0.0.
        30 01 30 01 30 01 30 01 30 01 30 01 30 01 30 01  0.0.0.0.0.0.0.0.
        30 01 30 01 38 01 65 01 66 03 69 70 36 04 61 72  0.0.8.e.f.ip6.ar
        70 61                                            pa              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3106 [hci0] 78938.895798
      Channel: 64 len 50 sdu 70 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 ff 00 01 0b 72 61 73 70 62 65 72 72 79 70  ......raspberryp
        69 05 6c 6f 63 61 6c 00 00 ff 00 01 c0 5a 00 1c  i.local......Z..
        00 01 00 00 00 78 00 10 fe 80 00 00 00 00 00 00  .....x..........
        f3 a0                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3107 [hci0] 78938.912064
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 24            #3108 [hci0] 78938.912172
      Channel: 64 len 20 sdu 20 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        b4 ff fe 37 e4 33 c0 0c 00 0c 00 01 00 00 00 78  ...7.3.........x
        00 02 c0 5a                                      ...Z            
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3109 [hci0] 78938.913449
      LE L2CAP: LE Flow Control Credit (0x16) ident 49 len 4
        Source CID: 64
        Credits: 3
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3110 [hci0] 78938.941064
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3111 [hci0] 78938.942061
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3112 [hci0] 78938.943058
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3113 [hci0] 78938.971068
      LE L2CAP: LE Flow Control Credit (0x16) ident 51 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3114 [hci0] 78939.096498
      Channel: 64 len 50 sdu 156 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        9c 00 6f 3b 09 4c d9 fb f0 14 e9 14 e9 43 80 00  ..o;.L.......C..
        00 84 00 00 00 00 02 00 00 00 00 01 33 01 33 01  ............3.3.
        34 01 65 01 37 01 33 01 65 01 66 01 66 01 66 01  4.e.7.3.e.f.f.f.
        34 01                                            4.              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3115 [hci0] 78939.096545
      Channel: 64 len 50 sdu 108 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        62 01 30 01 61 01 33 01 66 01 30 01 30 01 30 01  b.0.a.3.f.0.0.0.
        30 01 30 01 30 01 30 01 30 01 30 01 30 01 30 01  0.0.0.0.0.0.0.0.
        30 01 30 01 38 01 65 01 66 03 69 70 36 04 61 72  0.0.8.e.f.ip6.ar
        70 61                                            pa              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3116 [hci0] 78939.096569
      Channel: 64 len 50 sdu 58 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 0c 80 01 00 00 00 78 00 13 0b 72 61 73 70  ........x...rasp
        62 65 72 72 79 70 69 05 6c 6f 63 61 6c 00 c0 60  berrypi.local..`
        00 1c 80 01 00 00 00 78 00 10 fe 80 00 00 00 00  .......x........
        00 00                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3117 [hci0] 78939.122060
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 12            #3118 [hci0] 78939.122137
      Channel: 64 len 8 sdu 8 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        f3 a0 b4 ff fe 37 e4 33                          .....7.3        
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3119 [hci0] 78939.122443
      LE L2CAP: LE Flow Control Credit (0x16) ident 53 len 4
        Source CID: 64
        Credits: 3
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3120 [hci0] 78939.152062
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3121 [hci0] 78939.153061
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3122 [hci0] 78939.181060
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3123 [hci0] 78939.211314
      LE L2CAP: LE Flow Control Credit (0x16) ident 55 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3124 [hci0] 78940.344230
      Channel: 64 len 50 sdu 156 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        9c 00 6f 3b 09 4c d9 fb f0 14 e9 14 e9 43 80 00  ..o;.L.......C..
        00 84 00 00 00 00 02 00 00 00 00 01 33 01 33 01  ............3.3.
        34 01 65 01 37 01 33 01 65 01 66 01 66 01 66 01  4.e.7.3.e.f.f.f.
        34 01                                            4.              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3125 [hci0] 78940.344288
      Channel: 64 len 50 sdu 108 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        62 01 30 01 61 01 33 01 66 01 30 01 30 01 30 01  b.0.a.3.f.0.0.0.
        30 01 30 01 30 01 30 01 30 01 30 01 30 01 30 01  0.0.0.0.0.0.0.0.
        30 01 30 01 38 01 65 01 66 03 69 70 36 04 61 72  0.0.8.e.f.ip6.ar
        70 61                                            pa              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3126 [hci0] 78940.344312
      Channel: 64 len 50 sdu 58 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 0c 80 01 00 00 00 78 00 13 0b 72 61 73 70  ........x...rasp
        62 65 72 72 79 70 69 05 6c 6f 63 61 6c 00 c0 60  berrypi.local..`
        00 1c 80 01 00 00 00 78 00 10 fe 80 00 00 00 00  .......x........
        00 00                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3127 [hci0] 78940.352060
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 12            #3128 [hci0] 78940.352139
      Channel: 64 len 8 sdu 8 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        f3 a0 b4 ff fe 37 e4 33                          .....7.3        
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3129 [hci0] 78940.352686
      LE L2CAP: LE Flow Control Credit (0x16) ident 57 len 4
        Source CID: 64
        Credits: 3
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3130 [hci0] 78940.381085
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3131 [hci0] 78940.382061
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3132 [hci0] 78940.383058
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3133 [hci0] 78940.411574
      LE L2CAP: LE Flow Control Credit (0x16) ident 59 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3134 [hci0] 78942.592094
      Channel: 64 len 50 sdu 156 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        9c 00 6f 3b 09 4c d9 fb f0 14 e9 14 e9 43 80 00  ..o;.L.......C..
        00 84 00 00 00 00 02 00 00 00 00 01 33 01 33 01  ............3.3.
        34 01 65 01 37 01 33 01 65 01 66 01 66 01 66 01  4.e.7.3.e.f.f.f.
        34 01                                            4.              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3135 [hci0] 78942.592150
      Channel: 64 len 50 sdu 108 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        62 01 30 01 61 01 33 01 66 01 30 01 30 01 30 01  b.0.a.3.f.0.0.0.
        30 01 30 01 30 01 30 01 30 01 30 01 30 01 30 01  0.0.0.0.0.0.0.0.
        30 01 30 01 38 01 65 01 66 03 69 70 36 04 61 72  0.0.8.e.f.ip6.ar
        70 61                                            pa              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3136 [hci0] 78942.592175
      Channel: 64 len 50 sdu 58 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 0c 80 01 00 00 00 78 00 13 0b 72 61 73 70  ........x...rasp
        62 65 72 72 79 70 69 05 6c 6f 63 61 6c 00 c0 60  berrypi.local..`
        00 1c 80 01 00 00 00 78 00 10 fe 80 00 00 00 00  .......x........
        00 00                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3137 [hci0] 78942.602051
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 12            #3138 [hci0] 78942.602132
      Channel: 64 len 8 sdu 8 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        f3 a0 b4 ff fe 37 e4 33                          .....7.3        
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3139 [hci0] 78942.602929
      LE L2CAP: LE Flow Control Credit (0x16) ident 61 len 4
        Source CID: 64
        Credits: 3
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3140 [hci0] 78942.631051
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3141 [hci0] 78942.632050
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3142 [hci0] 78942.633053
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3143 [hci0] 78942.660554
      LE L2CAP: LE Flow Control Credit (0x16) ident 63 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3144 [hci0] 78953.452420
      Channel: 64 len 50 sdu 63 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3f 00 6b 3b 01 90 f5 3a 01 86 00 22 07 40 00 07  ?.k;...:...".@..
        08 00 00 00 00 00 00 00 00 03 04 40 60 00 01 51  ...........@`..Q
        80 00 00 38 40 00 00 00 00 20 01 0d b8 00 00 00  ...8@.... ......
        00 00                                            ..              
< ACL Data TX: Handle 0 flags 0x00 dlen 19            #3145 [hci0] 78953.452474
      Channel: 64 len 15 sdu 15 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 01 01 f3 a0 b4 37 e4 33     ............7.3 
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3146 [hci0] 78953.461022
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3147 [hci0] 78953.462019
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3148 [hci0] 78953.463149
      LE L2CAP: LE Flow Control Credit (0x16) ident 65 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3149 [hci0] 78953.490772
      LE L2CAP: LE Flow Control Credit (0x16) ident 67 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3150 [hci0] 78969.469263
      Channel: 64 len 50 sdu 63 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3f 00 6b 3b 01 90 f5 3a 01 86 00 22 07 40 00 07  ?.k;...:...".@..
        08 00 00 00 00 00 00 00 00 03 04 40 60 00 01 51  ...........@`..Q
        80 00 00 38 40 00 00 00 00 20 01 0d b8 00 00 00  ...8@.... ......
        00 00                                            ..              
< ACL Data TX: Handle 0 flags 0x00 dlen 19            #3151 [hci0] 78969.469314
      Channel: 64 len 15 sdu 15 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 01 01 f3 a0 b4 37 e4 33     ............7.3 
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3152 [hci0] 78969.479975
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3153 [hci0] 78969.480973
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3154 [hci0] 78969.482102
      LE L2CAP: LE Flow Control Credit (0x16) ident 69 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3155 [hci0] 78969.509726
      LE L2CAP: LE Flow Control Credit (0x16) ident 71 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Disconnect Complete (0x05) plen 4        #3156 [hci0] 78974.338965
        Status: Success (0x00)
        Handle: 0
        Reason: LMP Response Timeout / LL Response Timeout (0x22)
@ MGMT Event: Device Disconnected (0x000c) plen 8  {0x0002} [hci0] 78974.339038
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Reason: Unspecified (0x00)
@ MGMT Event: Device Disconnected (0x000c) plen 8  {0x0001} [hci0] 78974.339038
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Reason: Unspecified (0x00)

In case that anything wrong in my startup config, the reset btmon is shown below:

Bluetooth monitor ver 5.54
= Note: Linux version 5.4.51-v7+ (armv7l)                              0.929263
= Note: Bluetooth subsystem version 2.22                               0.929273
= New Index: 00:00:00:00:00:00 (Primary,USB,hci0)               [hci0] 0.929277
= Open Index: 00:00:00:00:00:00                                 [hci0] 0.929280
= Index Info: 00:00:00:00:00:00 (Nordic Semiconductor ASA)      [hci0] 0.929285
@ MGMT Open: bluetoothd (privileged) version 1.14             {0x0002} 0.929290
@ MGMT Open: btmon (privileged) version 1.14                  {0x0001} 0.929642
@ RAW Open: hciconfig (privileged) version 2.22               {0x0003} 5.368125
@ MGMT Event: New Settings (0x0006) plen 4             {0x0001} [hci0] 5.368254
        Current settings: 0x00008a10
          Bondable
          Low Energy
          Secure Connections
          Static Address
@ MGMT Event: New Settings (0x0006) plen 4             {0x0002} [hci0] 5.368254
        Current settings: 0x00008a10
          Bondable
          Low Energy
          Secure Connections
          Static Address
= Close Index: 00:00:00:00:00:00                                [hci0] 5.368344
= Open Index: 00:00:00:00:00:00                                 [hci0] 5.368540
< HCI Command: Reset (0x03|0x0003) plen 0                    #1 [hci0] 5.368610
> HCI Event: Command Complete (0x0e) plen 4                  #2 [hci0] 5.369921
      Reset (0x03|0x0003) ncmd 1
        Status: Success (0x00)
< HCI Command: Read Local Supported... (0x04|0x0003) plen 0  #3 [hci0] 5.370005
> HCI Event: Command Complete (0x0e) plen 12                 #4 [hci0] 5.371933
      Read Local Supported Features (0x04|0x0003) ncmd 1
        Status: Success (0x00)
        Features: 0x00 0x00 0x00 0x00 0x60 0x00 0x00 0x00
          BR/EDR Not Supported
          LE Supported (Controller)
< HCI Command: Read Local Version In.. (0x04|0x0001) plen 0  #5 [hci0] 5.372035
> HCI Event: Command Complete (0x0e) plen 12                 #6 [hci0] 5.374918
      Read Local Version Information (0x04|0x0001) ncmd 1
        Status: Success (0x00)
        HCI version: Reserved (0x0b) - Revision 4331 (0x10eb)
        LMP version: Reserved (0x0b) - Subversion 4331 (0x10eb)
        Manufacturer: Nordic Semiconductor ASA (89)
< HCI Command: Read BD ADDR (0x04|0x0009) plen 0             #7 [hci0] 5.374991
> HCI Event: Command Complete (0x0e) plen 10                 #8 [hci0] 5.375907
      Read BD ADDR (0x04|0x0009) ncmd 1
        Status: Success (0x00)
        Address: 00:00:00:00:00:00 (OUI 00-00-00)
< HCI Command: LE Read Buffer Size (0x08|0x0002) plen 0      #9 [hci0] 5.376047
> HCI Event: Command Complete (0x0e) plen 7                 #10 [hci0] 5.377906
      LE Read Buffer Size (0x08|0x0002) ncmd 1
        Status: Success (0x00)
        Data packet length: 251
        Num data packets: 3
< HCI Command: LE Read Local Suppor.. (0x08|0x0003) plen 0  #11 [hci0] 5.377973
> HCI Event: Command Complete (0x0e) plen 12                #12 [hci0] 5.379930
      LE Read Local Supported Features (0x08|0x0003) ncmd 1
        Status: Success (0x00)
        Features: 0xf5 0x59 0x00 0x00 0x00 0x00 0x00 0x00
          LE Encryption
          Extended Reject Indication
          LE Ping
          LE Data Packet Length Extension
          LL Privacy
          Extended Scanner Filter Policies
          LE 2M PHY
          LE Coded PHY
          LE Extended Advertising
          Channel Selection Algorithm #2
< HCI Command: LE Read Supported St.. (0x08|0x001c) plen 0  #13 [hci0] 5.380000
> HCI Event: Command Complete (0x0e) plen 12                #14 [hci0] 5.380916
      LE Read Supported States (0x08|0x001c) ncmd 1
        Status: Success (0x00)
        States: 0x000003ffff3fffff
          Non-connectable Advertising State
          Scannable Advertising State
          Connectable Advertising State
          High Duty Cycle Directed Advertising State
          Passive Scanning State
          Active Scanning State
          Initiating State
            and Connection State (Master Role)
          Connection State (Slave Role)
          Non-connectable Advertising State
            and Passive Scanning State
          Scannable Advertising State
            and Passive Scanning State
          Connectable Advertising State
            and Passive Scanning State
          High Duty Cycle Directed Advertising State
            and Passive Scanning State
          Non-connectable Advertising State
            and Active Scanning State
          Scannable Advertising State
            and Active Scanning State
          Connectable Advertising State
            and Active Scanning State
          High Duty Cycle Directed Advertising State
            and Active Scanning State
          Non-connectable Advertising State
            and Initiating State
          Scannable Advertising State
            and Initiating State
          Non-connectable Advertising State
            and Connection State (Master Role)
          Scannable Advertising State
            and Connection State (Master Role)
          Non-connectable Advertising State
            and Connection State (Slave Role)
          Scannable Advertising State
            and Connection State (Slave Role)
          Passive Scanning State
            and Connection State (Master Role)
          Active Scanning State
            and Connection State (Master Role)
          Passive Scanning State
            and Connection State (Slave Role)
          Active Scanning State
            and Connection State (Slave Role)
          Initiating State
            and Connection State (Master Role)
            and Master Role & Master Role
          Low Duty Cycle Directed Advertising State
          Low Duty Cycle Directed Advertising State
            and Passive Scanning State
          Low Duty Cycle Directed Advertising State
            and Active Scanning State
          Connectable Advertising State
            and Initiating State
            and Master Role & Slave Role
          High Duty Cycle Directed Advertising State
            and Initiating State
            and Master Role & Slave Role
          Low Duty Cycle Directed Advertising State
            and Initiating State
            and Master Role & Slave Role
          Connectable Advertising State
            and Connection State (Master Role)
            and Master Role & Slave Role
          High Duty Cycle Directed Advertising State
            and Connection State (Master Role)
            and Master Role & Slave Role
          Low Duty Cycle Directed Advertising State
            and Connection State (Master Role)
            and Master Role & Slave Role
          Connectable Advertising State
            and Connection State (Slave Role)
            and Master Role & Slave Role
          High Duty Cycle Directed Advertising State
            and Connection State (Slave Role)
            and Slave Role & Slave Role
          Low Duty Cycle Directed Advertising State
            and Connection State (Slave Role)
            and Slave Role & Slave Role
          Initiating State
            and Connection State (Slave Role)
            and Master Role & Slave Role
< HCI Command: Read Local Supported.. (0x04|0x0002) plen 0  #15 [hci0] 5.380978
> HCI Event: Command Complete (0x0e) plen 68                #16 [hci0] 5.383907
      Read Local Supported Commands (0x04|0x0002) ncmd 1
        Status: Success (0x00)
        Commands: 74 entries
          Disconnect (Octet 0 - Bit 5)
          Read Remote Version Information (Octet 2 - Bit 7)
          Set Event Mask (Octet 5 - Bit 6)
          Reset (Octet 5 - Bit 7)
          Read Transmit Power Level (Octet 10 - Bit 2)
          Set Controller To Host Flow Control (Octet 10 - Bit 5)
          Host Buffer Size (Octet 10 - Bit 6)
          Host Number of Completed Packets (Octet 10 - Bit 7)
          Read Local Version Information (Octet 14 - Bit 3)
          Read Local Supported Features (Octet 14 - Bit 5)
          Read BD ADDR (Octet 15 - Bit 1)
          Read RSSI (Octet 15 - Bit 5)
          Set Event Mask Page 2 (Octet 22 - Bit 2)
          LE Set Event Mask (Octet 25 - Bit 0)
          LE Read Buffer Size (Octet 25 - Bit 1)
          LE Read Local Supported Features (Octet 25 - Bit 2)
          LE Set Random Address (Octet 25 - Bit 4)
          LE Set Advertising Parameters (Octet 25 - Bit 5)
          LE Read Advertising Channel TX Power (Octet 25 - Bit 6)
          LE Set Advertising Data (Octet 25 - Bit 7)
          LE Set Scan Response Data (Octet 26 - Bit 0)
          LE Set Advertise Enable (Octet 26 - Bit 1)
          LE Set Scan Parameters (Octet 26 - Bit 2)
          LE Set Scan Enable (Octet 26 - Bit 3)
          LE Create Connection (Octet 26 - Bit 4)
          LE Create Connection Cancel (Octet 26 - Bit 5)
          LE Read White List Size (Octet 26 - Bit 6)
          LE Clear White List (Octet 26 - Bit 7)
          LE Add Device To White List (Octet 27 - Bit 0)
          LE Remove Device From White List (Octet 27 - Bit 1)
          LE Connection Update (Octet 27 - Bit 2)
          LE Set Host Channel Classification (Octet 27 - Bit 3)
          LE Read Channel Map (Octet 27 - Bit 4)
          LE Read Remote Used Features (Octet 27 - Bit 5)
          LE Encrypt (Octet 27 - Bit 6)
          LE Rand (Octet 27 - Bit 7)
          LE Start Encryption (Octet 28 - Bit 0)
          LE Long Term Key Request Reply (Octet 28 - Bit 1)
          LE Long Term Key Request Neg Reply (Octet 28 - Bit 2)
          LE Read Supported States (Octet 28 - Bit 3)
          LE Receiver Test (Octet 28 - Bit 4)
          LE Transmitter Test (Octet 28 - Bit 5)
          LE Test End (Octet 28 - Bit 6)
          Read Authenticated Payload Timeout (Octet 32 - Bit 4)
          Write Authenticated Payload Timeout (Octet 32 - Bit 5)
          LE Set Data Length (Octet 33 - Bit 6)
          LE Read Suggested Default Data Length (Octet 33 - Bit 7)
          LE Write Suggested Default Data Length (Octet 34 - Bit 0)
          LE Add Device To Resolving List (Octet 34 - Bit 3)
          LE Remove Device From Resolving List (Octet 34 - Bit 4)
          LE Clear Resolving List (Octet 34 - Bit 5)
          LE Read Resolving List Size (Octet 34 - Bit 6)
          LE Set Address Resolution Enable (Octet 35 - Bit 1)
          LE Set Resolvable Private Address Timeout (Octet 35 - Bit 2)
          LE Read Maximum Data Length (Octet 35 - Bit 3)
          LE Read PHY (Octet 35 - Bit 4)
          LE Set Default PHY (Octet 35 - Bit 5)
          LE Set PHY (Octet 35 - Bit 6)
          LE Enhanced Receiver Test (Octet 35 - Bit 7)
          LE Enhanced Transmitter Test (Octet 36 - Bit 0)
          LE Set Advertising Set Random Address (Octet 36 - Bit 1)
          LE Set Extended Advertising Parameters (Octet 36 - Bit 2)
          LE Set Extended Advertising Data (Octet 36 - Bit 3)
          LE Set Extended Scan Response Data (Octet 36 - Bit 4)
          LE Set Extended Advertising Enable (Octet 36 - Bit 5)
          LE Read Maximum Advertising Data Length (Octet 36 - Bit 6)
          LE Read Number of Supported Advertising Sets (Octet 36 - Bit 7)
          LE Remove Advertising Set (Octet 37 - Bit 0)
          LE Clear Advertising Sets (Octet 37 - Bit 1)
          LE Set Extended Scan Parameters (Octet 37 - Bit 5)
          LE Set Extended Scan Enable (Octet 37 - Bit 6)
          LE Extended Create Connection (Octet 37 - Bit 7)
          LE Read Transmit Power (Octet 38 - Bit 7)
          LE Set Privacy Mode (Octet 39 - Bit 2)
< HCI Command: Set Event Mask (0x03|0x0001) plen 8          #17 [hci0] 5.384072
        Mask: 0x200080000204e890
          Disconnection Complete
          Encryption Change
          Read Remote Version Information Complete
          Command Complete
          Command Status
          Hardware Error
          Number of Completed Packets
          Data Buffer Overflow
          Encryption Key Refresh Complete
          LE Meta
> HCI Event: Command Complete (0x0e) plen 4                 #18 [hci0] 5.384909
      Set Event Mask (0x03|0x0001) ncmd 1
        Status: Success (0x00)
< HCI Command: LE Set Event Mask (0x08|0x0001) plen 8       #19 [hci0] 5.384978
        Mask: 0x00000000000a1e5f
          LE Connection Complete
          LE Advertising Report
          LE Connection Update Complete
          LE Read Remote Used Features Complete
          LE Long Term Key Request
          LE Data Length Change
          LE Enhanced Connection Complete
          LE Direct Advertising Report
          LE PHY Update Complete
          LE Extended Advertising Report
          LE Extended Advertising Set Terminated
          LE Channel Selection Algorithm
> HCI Event: Command Complete (0x0e) plen 4                 #20 [hci0] 5.386999
      LE Set Event Mask (0x08|0x0001) ncmd 1
        Status: Success (0x00)
< HCI Command: LE Read White List S.. (0x08|0x000f) plen 0  #21 [hci0] 5.387066
> HCI Event: Command Complete (0x0e) plen 5                 #22 [hci0] 5.388919
      LE Read White List Size (0x08|0x000f) ncmd 1
        Status: Success (0x00)
        Size: 8
< HCI Command: LE Clear White List (0x08|0x0010) plen 0     #23 [hci0] 5.388985
> HCI Event: Command Complete (0x0e) plen 4                 #24 [hci0] 5.389908
      LE Clear White List (0x08|0x0010) ncmd 1
        Status: Success (0x00)
< HCI Command: LE Read Resolving Li.. (0x08|0x002a) plen 0  #25 [hci0] 5.390033
> HCI Event: Command Complete (0x0e) plen 5                 #26 [hci0] 5.391909
      LE Read Resolving List Size (0x08|0x002a) ncmd 1
        Status: Success (0x00)
        Size: 8
< HCI Command: LE Clear Resolving L.. (0x08|0x0029) plen 0  #27 [hci0] 5.391978
> HCI Event: Command Complete (0x0e) plen 4                 #28 [hci0] 5.393927
      LE Clear Resolving List (0x08|0x0029) ncmd 1
        Status: Success (0x00)
< HCI Command: LE Read Maximum Data.. (0x08|0x002f) plen 0  #29 [hci0] 5.394001
> HCI Event: Command Complete (0x0e) plen 12                #30 [hci0] 5.394911
      LE Read Maximum Data Length (0x08|0x002f) ncmd 1
        Status: Success (0x00)
        Max TX octets: 251
        Max TX time: 2704
        Max RX octets: 251
        Max RX time: 2704
< HCI Command: LE Read Suggested De.. (0x08|0x0023) plen 0  #31 [hci0] 5.394981
> HCI Event: Command Complete (0x0e) plen 8                 #32 [hci0] 5.396906
      LE Read Suggested Default Data Length (0x08|0x0023) ncmd 1
        Status: Success (0x00)
        TX octets: 27
        TX time: 328
< HCI Command: LE Read Number of Su.. (0x08|0x003b) plen 0  #33 [hci0] 5.396975
> HCI Event: Command Complete (0x0e) plen 5                 #34 [hci0] 5.398927
      LE Read Number of Supported Advertising Sets (0x08|0x003b) ncmd 1
        Status: Success (0x00)
        Num supported adv sets: 1
< HCI Command: Set Event Mask Page 2 (0x03|0x0063) plen 8   #35 [hci0] 5.399064
        Mask: 0x0000000000800000
          Authenticated Payload Timeout Expired
> HCI Event: Command Complete (0x0e) plen 4                 #36 [hci0] 5.401913
      Set Event Mask Page 2 (0x03|0x0063) ncmd 1
        Status: Success (0x00)
< HCI Command: LE Write Suggested D.. (0x08|0x0024) plen 4  #37 [hci0] 5.401982
        TX octets: 251
        TX time: 2704
> HCI Event: Command Complete (0x0e) plen 4                 #38 [hci0] 5.402905
      LE Write Suggested Default Data Length (0x08|0x0024) ncmd 1
        Status: Success (0x00)
< HCI Command: LE Set Default PHY (0x08|0x0031) plen 3      #39 [hci0] 5.402974
        All PHYs preference: 0x00
        TX PHYs preference: 0x01
          LE 1M
        RX PHYs preference: 0x01
          LE 1M
> HCI Event: Command Complete (0x0e) plen 4                 #40 [hci0] 5.404910
      LE Set Default PHY (0x08|0x0031) ncmd 1
        Status: Success (0x00)
= Index Info: 00:00:00:00:00:00 (Nordic Semiconductor ASA)      [hci0] 5.405006
< HCI Command: LE Set Extended Adv.. (0x08|0x0036) plen 25  #41 [hci0] 5.405204
        Handle: 0x00
        Properties: 0x0010
          Use legacy advertising PDUs: ADV_NONCONN_IND
        Min advertising interval: 1280.000 msec (0x0800)
        Max advertising interval: 1280.000 msec (0x0800)
        Channel map: 37, 38, 39 (0x07)
        Own address type: Random (0x01)
        Peer address type: Public (0x00)
        Peer address: 00:00:00:00:00:00 (OUI 00-00-00)
        Filter policy: Allow Scan Request from Any, Allow Connect Request from Any (0x00)
        TX power: 127 dbm (0x7f)
        Primary PHY: LE 1M (0x01)
        Secondary max skip: 0x00
        Secondary PHY: LE 1M (0x01)
        SID: 0x00
        Scan request notifications: Disabled (0x00)
> HCI Event: Command Complete (0x0e) plen 5                 #42 [hci0] 5.406927
      LE Set Extended Advertising Parameters (0x08|0x0036) ncmd 1
        Status: Success (0x00)
        TX power (selected): 0 dbm (0x00)
< HCI Command: LE Set Advertising S.. (0x08|0x0035) plen 7  #43 [hci0] 5.407011
        Advertising handle: 0x00
        Advertising random address: 24:10:1D:26:A6:C0 (OUI 24-10-1D)
> HCI Event: Command Complete (0x0e) plen 4                 #44 [hci0] 5.407909
      LE Set Advertising Set Random Address (0x08|0x0035) ncmd 1
        Status: Success (0x00)
< HCI Command: LE Set Extended Sca.. (0x08|0x0038) plen 35  #45 [hci0] 5.407977
        Handle: 0x00
        Operation: Complete scan response data (0x03)
        Fragment preference: Minimize fragmentation (0x01)
        Data length: 0x0d
        Name (short): raspberryp
> HCI Event: Command Complete (0x0e) plen 4                 #46 [hci0] 5.409911
      LE Set Extended Scan Response Data (0x08|0x0038) ncmd 1
        Status: Invalid HCI Command Parameters (0x12)
< HCI Command: LE Set Extended Adv.. (0x08|0x0037) plen 35  #47 [hci0] 5.409996
        Handle: 0x00
        Operation: Complete extended advertising data (0x03)
        Fragment preference: Minimize fragmentation (0x01)
        Data length: 0x06
        Flags: 0x04
          BR/EDR Not Supported
        TX power: 0 dBm
@ MGMT Event: New Settings (0x0006) plen 4             {0x0001} [hci0] 5.410056
        Current settings: 0x00008a11
          Powered
          Bondable
          Low Energy
          Secure Connections
          Static Address
@ MGMT Event: New Settings (0x0006) plen 4             {0x0002} [hci0] 5.410056
        Current settings: 0x00008a11
          Powered
          Bondable
          Low Energy
          Secure Connections
          Static Address
@ RAW Close: hciconfig                                        {0x0003} 5.410926
> HCI Event: Command Complete (0x0e) plen 4                 #48 [hci0] 5.411952
      LE Set Extended Advertising Data (0x08|0x0037) ncmd 1
        Status: Success (0x00)

Impact
Showstopper for my project.

Environment (please complete the following information):

  • HCI Device: nrf52840 Dongle and nrf52840-DK (PCA10059 and PCA10056)
  • Host Device: Raspberry Pi 3 B+
  • Host OS: Linux Raspbian, Kernel version: 5.4.51-v7+
  • Toolchain: nRF Connect SDK (Zephyr OS with nrfxlib)
  • HCI device and clients' connection parameters are exactly consistent.

Any help will appreciated, thanks in advance.

@kasapreyizz kasapreyizz added the bug The issue is a bug, or the PR is fixing a bug label Nov 4, 2020
@joerchan
Copy link
Contributor

joerchan commented Nov 4, 2020

@kasapreyizz Can you expand on the setup, which device is master, which device is slave. Which side is the one disconnecting.
In your logs you have both sides of the disconnect reason. It appears that the master dropped the connection because an LL procedure did not complete within the timeout (40 seconds, and disconnect reason: LMP Response Timeout / LL Response Timeout (0x22))

If you have the full logs from both sides can you attach them as files in full (zipped) to the issue?

@kasapreyizz
Copy link
Author

kasapreyizz commented Nov 4, 2020

Hello there @joerchan,

I got an nrf52dongle programmed with hci_usb sample. The master is nrf52dongle and it establish the connections via HCI commands at host side. Here we got somethink weird, with legacy scanning and connection settings, that disconnects doesnt happen. But with extended scanning and enhanced connection it disconnects. And it disconnects every single extended device and with all initiating PHYs. So I think that the problem at master.

When no data is exchanged between initiator and acceptor the disconnect reason looks 0x13 and 0x16 respectively. But when I connect them via hcitool and than a 6lowpan tcp connection is established disconnect happens and that time reason LMP / LL Response Timeout.

I have logged full of HCI_USB side of my setup in original entry and my IPSP device does not have any debug option. If I attach them again, they mostly look like same. But I know that IPSP device have not any problem since an mobile device can connect it without connection lost issue (via Coded PHY) and I tried much samples to connect with my hci_usb device and all of them I got disconnected issue.

Thanks in advance.

@joerchan
Copy link
Contributor

joerchan commented Nov 4, 2020

The 0x13/0x16 I'm not so sure about.

> HCI Event: Disconnect Complete (0x05) plen 4        #2809 [hci0] 12837.631685
        Status: Success (0x00)
        Handle: 0
        Reason: Connection Timeout (0x08)
@ MGMT Event: Device Disconnected (0x000c) plen 8  {0x0002} [hci0] 12837.631741
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Reason: Connection timeout (0x01)
@ MGMT Event: Device Disconnected (0x000c) plen 8  {0x0001} [hci0] 12837.631741
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Reason: Connection timeout (0x01)
< HCI Command: Disconnect (0x01|0x0006) plen 3        #2810 [hci0] 12839.696163
        Handle: 1
        Reason: Remote User Terminated Connection (0x13)
> HCI Event: Command Status (0x0f) plen 4             #2811 [hci0] 12839.698692
      Disconnect (0x01|0x0006) ncmd 1
        Status: Success (0x00)
> HCI Event: Disconnect Complete (0x05) plen 4        #2812 [hci0] 12839.739683
        Status: Success (0x00)
        Handle: 1
        Reason: Connection Terminated By Local Host (0x16)

As you can see here, it is first disconnected with reason 0x08 (timeout). And then for some reason there is a Disconnect command, and a disconnect complete event with a different reason.

Can you give me an example on connecting two samples that would result in this disconnect problem? Since we don't have access to your IPSP device.

@kasapreyizz
Copy link
Author

I just tried now with peripheral_long_range sample which provided by nordic.
For reproduce that error: I got ncs 1.3.1 hci_usb sample on nrf52dongle with the prj.conf in original post
And I just flashed another nrf52dk with peripheral_long_range sample. There is no data exchange between them but it's still disconnects.

Here is the btmon full log:

Scanning device:

< HCI Command: LE Set Extende.. (0x08|0x0041) plen 9  #3157 [hci0] 88438.012359
        Own address type: Random (0x01)
        Filter policy: Accept all advertisement (0x00)
        PHYs: 0x04
        Entry 0: LE Coded
          Type: Active (0x01)
          Interval: 160.000 msec (0x0100)
          Window: 160.625 msec (0x0101)
> HCI Event: Command Complete (0x0e) plen 4           #3158 [hci0] 88438.013932
      LE Set Extended Scan Parameters (0x08|0x0041) ncmd 1
        Status: Success (0x00)
@ RAW Close: hcitool                               {0x0003} [hci0] 88438.014104
@ RAW Open: hcitool (privileged) version 2.22             {0x0003} 88443.214873
@ RAW Close: hcitool                                      {0x0003} 88443.214933
@ RAW Open: hcitool (privileged) version 2.22             {0x0003} 88443.215011
@ RAW Close: hcitool                                      {0x0003} 88443.215044
@ RAW Open: hcitool (privileged) version 2.22      {0x0003} [hci0] 88443.215150
< HCI Command: LE Set Extende.. (0x08|0x0042) plen 6  #3159 [hci0] 88443.215444
        Extended scan: Enabled (0x01)
        Filter duplicates: Disabled (0x00)
        Duration: 0 msec (0x0000)
        Period: 0.00 sec (0x0000)
> HCI Event: Command Complete (0x0e) plen 4           #3160 [hci0] 88443.216913
      LE Set Extended Scan Enable (0x08|0x0042) ncmd 1
        Status: Success (0x00)
@ RAW Close: hcitool                               {0x0003} [hci0] 88443.217083
> HCI Event: LE Meta Event (0x3e) plen 46             #3161 [hci0] 88443.344915
      LE Extended Advertising Report (0x0d)
        Num reports: 1
        Entry 0
          Event type: 0x0001
            Props: 0x0001
              Connectable
            Data status: Complete
          Address type: Random (0x01)
          Address: EF:85:C0:06:F8:9B (Static)
          Primary PHY: LE Coded
          Secondary PHY: LE Coded
          SID: 0x00
          TX power: 127 dBm
          RSSI: -27 dBm (0xe5)
          Periodic advertising invteral: 0.00 msec (0x0000)
          Direct address type: Reserved (0xff)
          Direct address: 00-00-00-00-00-00
          Data length: 0x14
        02 01 06 10 09 4c 6f 6e 67 20 72 61 6e 67 65 20  .....Long range 
        64 65 6d 6f                                      demo            

Connecting device and disconnect:


< HCI Command: LE Set Extende.. (0x08|0x0042) plen 6  #3177 [hci0] 88447.478143
        Extended scan: Disabled (0x00)
        Filter duplicates: Disabled (0x00)
        Duration: 0 msec (0x0000)
        Period: 0.00 sec (0x0000)
> HCI Event: Command Complete (0x0e) plen 4           #3178 [hci0] 88447.479904
      LE Set Extended Scan Enable (0x08|0x0042) ncmd 1
        Status: Success (0x00)
@ RAW Close: hcitool                               {0x0003} [hci0] 88447.480085

@ RAW Open: hcitool (privileged) version 2.22             {0x0003} 88524.241512
@ RAW Close: hcitool                                      {0x0003} 88524.241605
@ RAW Open: hcitool (privileged) version 2.22             {0x0003} 88524.241685
@ RAW Close: hcitool                                      {0x0003} 88524.241715
@ RAW Open: hcitool (privileged) version 2.22      {0x0003} [hci0] 88524.241832
< HCI Command: LE Extended C.. (0x08|0x0043) plen 26  #3179 [hci0] 88524.242479
        Filter policy: White list is not used (0x00)
        Own address type: Random (0x01)
        Peer address type: Random (0x01)
        Peer address: EF:85:C0:06:F8:9B (Static)
        Initiating PHYs: 0x04
        Entry 0: LE Coded
          Scan interval: 160.000 msec (0x0100)
          Scan window: 160.000 msec (0x0100)
          Min connection interval: 7.50 msec (0x0006)
          Max connection interval: 30.00 msec (0x0018)
          Connection latency: 0 (0x0000)
          Supervision timeout: 32000 msec (0x0c80)
          Min connection length: 0.000 msec (0x0000)
          Max connection length: 11360.000 msec (0x4700)
> HCI Event: Command Status (0x0f) plen 4             #3180 [hci0] 88524.244739
      LE Extended Create Connection (0x08|0x0043) ncmd 1
        Status: Success (0x00)
@ RAW Close: hcitool                               {0x0003} [hci0] 88524.245075
> HCI Event: LE Meta Event (0x3e) plen 31             #3181 [hci0] 88524.269738
      LE Enhanced Connection Complete (0x0a)
        Status: Success (0x00)
        Handle: 0
        Role: Master (0x00)
        Peer address type: Random (0x01)
        Peer address: EF:85:C0:06:F8:9B (Static)
        Local resolvable private address: 00:00:00:00:00:00 (Non-Resolvable)
        Peer resolvable private address: 00:00:00:00:00:00 (Non-Resolvable)
        Connection interval: 30.00 msec (0x0018)
        Connection latency: 0 (0x0000)
        Supervision timeout: 32000 msec (0x0c80)
        Master clock accuracy: 0x00
@ MGMT Event: Device Connected (0x000b) plen 13    {0x0002} [hci0] 88524.269821
        LE Address: EF:85:C0:06:F8:9B (Static)
        Flags: 0x00000000
        Data length: 0
@ MGMT Event: Device Connected (0x000b) plen 13    {0x0001} [hci0] 88524.269821
        LE Address: EF:85:C0:06:F8:9B (Static)
        Flags: 0x00000000
        Data length: 0
< HCI Command: LE Read Remote.. (0x08|0x0016) plen 2  #3182 [hci0] 88524.270126
        Handle: 0
> HCI Event: LE Meta Event (0x3e) plen 4              #3183 [hci0] 88524.270743
      LE Channel Selection Algorithm (0x14)
        Handle: 0
        Algorithm: #2 (0x01)
> HCI Event: Command Status (0x0f) plen 4             #3184 [hci0] 88524.272762
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
> HCI Event: LE Meta Event (0x3e) plen 12             #3185 [hci0] 88524.333762
      LE Read Remote Used Features (0x04)
        Status: Success (0x00)
        Handle: 0
        Features: 0x25 0x41 0x00 0x00 0x00 0x00 0x00 0x00
          LE Encryption
          Extended Reject Indication
          LE Data Packet Length Extension
          LE 2M PHY
          Channel Selection Algorithm #2
= bluetoothd: No cache for EF:85:C0:06:F8:9B                       88524.338844
< ACL Data TX: Handle 0 flags 0x00 dlen 7             #3186 [hci0] 88524.335391
      ATT: Exchange MTU Request (0x02) len 2
        Client RX MTU: 517
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3187 [hci0] 88524.393756
        Num handles: 1
        Handle: 0
        Count: 1

< HCI Command: Disconnect (0x01|0x0006) plen 3        #3188 [hci0] 88556.416155
        Handle: 0
        Reason: Remote User Terminated Connection (0x13)
> HCI Event: Command Status (0x0f) plen 4             #3189 [hci0] 88556.418667
      Disconnect (0x01|0x0006) ncmd 1
        Status: Success (0x00)
> HCI Event: Disconnect Complete (0x05) plen 4        #3190 [hci0] 88556.461664
        Status: Success (0x00)
        Handle: 0
        Reason: Connection Terminated By Local Host (0x16)
@ MGMT Event: Device Disconnected (0x000c) plen 8  {0x0002} [hci0] 88556.461720
        LE Address: EF:85:C0:06:F8:9B (Static)
        Reason: Connection terminated by local host (0x02)
@ MGMT Event: Device Disconnected (0x000c) plen 8  {0x0001} [hci0] 88556.461720
        LE Address: EF:85:C0:06:F8:9B (Static)
        Reason: Connection terminated by local host (0x02)

And I just tried that with iot tcp_client example. That produces the exact same error with mine error:

For reproduce that error, in nRF SDK 15, IoT examples, TCP Client sample, changing the following lines ipv6_medium_ble.c file:

    m_adv_params.advparams.properties.type = BLE_GAP_ADV_TYPE_EXTENDED_CONNECTABLE_NONSCANNABLE_UNDIRECTED;
    m_adv_params.advparams.p_peer_addr     = NULL; // Undirected advertisement.
    m_adv_params.advparams.filter_policy   = BLE_GAP_ADV_FP_ANY;
    m_adv_params.advparams.interval        = APP_ADV_ADV_INTERVAL;
    m_adv_params.advparams.duration        = APP_ADV_DURATION;
    m_adv_params.advparams.primary_phy   	 = BLE_GAP_PHY_CODED;

Than again btmon logs as following:

Scanning:

> HCI Event: LE Meta Event (0x3e) plen 39             #3261 [hci0] 88855.837938
      LE Extended Advertising Report (0x0d)
        Num reports: 1
        Entry 0
          Event type: 0x0001
            Props: 0x0001
              Connectable
            Data status: Complete
          Address type: Public (0x00)
          Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
          Primary PHY: LE Coded
          Secondary PHY: LE 1M
          SID: 0x00
          TX power: 127 dBm
          RSSI: -36 dBm (0xdc)
          Periodic advertising invteral: 0.00 msec (0x0000)
          Direct address type: Reserved (0xff)
          Direct address: 00-00-00-00-00-00
          Data length: 0x0d
        02 01 04 03 03 20 18 05 09 54 43 50 32           ..... ...TCP2  

Establishing connection:

< HCI Command: LE Extended C.. (0x08|0x0043) plen 26  #3284 [hci0] 88972.861794
        Filter policy: White list is not used (0x00)
        Own address type: Random (0x01)
        Peer address type: Public (0x00)
        Peer address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Initiating PHYs: 0x04
        Entry 0: LE Coded
          Scan interval: 160.000 msec (0x0100)
          Scan window: 160.000 msec (0x0100)
          Min connection interval: 7.50 msec (0x0006)
          Max connection interval: 30.00 msec (0x0018)
          Connection latency: 0 (0x0000)
          Supervision timeout: 32000 msec (0x0c80)
          Min connection length: 0.000 msec (0x0000)
          Max connection length: 11360.000 msec (0x4700)
> HCI Event: Command Status (0x0f) plen 4             #3285 [hci0] 88972.863603
      LE Extended Create Connection (0x08|0x0043) ncmd 1
        Status: Success (0x00)
@ RAW Close: hcitool                               {0x0003} [hci0] 88972.863795
> HCI Event: LE Meta Event (0x3e) plen 31             #3286 [hci0] 88973.018604
      LE Enhanced Connection Complete (0x0a)
        Status: Success (0x00)
        Handle: 0
        Role: Master (0x00)
        Peer address type: Public (0x00)
        Peer address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Local resolvable private address: 00:00:00:00:00:00 (Non-Resolvable)
        Peer resolvable private address: 00:00:00:00:00:00 (Non-Resolvable)
        Connection interval: 30.00 msec (0x0018)
        Connection latency: 0 (0x0000)
        Supervision timeout: 32000 msec (0x0c80)
        Master clock accuracy: 0x00
@ MGMT Event: Device Connected (0x000b) plen 13    {0x0002} [hci0] 88973.018688
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Flags: 0x00000000
        Data length: 0
@ MGMT Event: Device Connected (0x000b) plen 13    {0x0001} [hci0] 88973.018688
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Flags: 0x00000000
        Data length: 0
< HCI Command: LE Read Remote.. (0x08|0x0016) plen 2  #3287 [hci0] 88973.019000
        Handle: 0
> HCI Event: LE Meta Event (0x3e) plen 4              #3288 [hci0] 88973.019611
      LE Channel Selection Algorithm (0x14)
        Handle: 0
        Algorithm: #2 (0x01)
> HCI Event: Command Status (0x0f) plen 4             #3289 [hci0] 88973.020614
      LE Read Remote Used Features (0x08|0x0016) ncmd 1
        Status: Success (0x00)
> HCI Event: LE Meta Event (0x3e) plen 12             #3290 [hci0] 88973.081630
      LE Read Remote Used Features (0x04)
        Status: Success (0x00)
        Handle: 0
        Features: 0x25 0x41 0x00 0x00 0x00 0x00 0x00 0x00
          LE Encryption
          Extended Reject Indication
          LE Data Packet Length Extension
          LE 2M PHY
          Channel Selection Algorithm #2
= bluetoothd: No cache for 00:85:C0:06:F8:9B                       88973.082646
< ACL Data TX: Handle 0 flags 0x00 dlen 7             #3291 [hci0] 88973.082879
      ATT: Exchange MTU Request (0x02) len 2
        Client RX MTU: 517
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3292 [hci0] 88973.141617
        Num handles: 1
        Handle: 0
        Count: 1

While that device is connected commanding echo "connect 00:85:C0:06:F8:9B 1" > /sys/kernel/debug/bluetooth/6lowpan_control it connects, exchanges data (in that case, I did not connect TCP but it connects when we got a proper setup)

< ACL Data TX: Handle 0 flags 0x00 dlen 18            #3459 [hci0] 89228.373923
      LE L2CAP: LE Connection Request (0x14) ident 1 len 10
        PSM: 35 (0x0023)
        Source CID: 64
        MTU: 1280
        MPS: 247
        Credits: 6
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3460 [hci0] 89228.385910
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 18            #3461 [hci0] 89228.415660
      LE L2CAP: LE Connection Response (0x15) ident 1 len 10
        Destination CID: 64
        MTU: 1280
        MPS: 50
        Credits: 0
        Result: Connection successful (0x0000)
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3462 [hci0] 89228.417045
      LE L2CAP: LE Flow Control Credit (0x16) ident 3 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3463 [hci0] 89228.418429
      LE L2CAP: LE Flow Control Credit (0x16) ident 5 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3464 [hci0] 89228.419813
      LE L2CAP: LE Flow Control Credit (0x16) ident 7 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3465 [hci0] 89228.444925
      LE L2CAP: LE Flow Control Credit (0x16) ident 9 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3466 [hci0] 89228.446344
      Channel: 64 len 50 sdu 60 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3c 00 79 4b 00 16 3a 00 05 02 00 00 01 00 8f 00  <.yK..:.........
        88 05 00 00 00 02 04 00 00 00 ff 02 00 00 00 00  ................
        00 00 00 00 00 01 ff 37 e4 33 04 00 00 00 ff 02  .......7.3......
        00 00                                            ..              
< ACL Data TX: Handle 0 flags 0x00 dlen 16            #3467 [hci0] 89228.446395
      Channel: 64 len 12 sdu 12 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 00 00 00 00 02              ............    
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3468 [hci0] 89228.475932
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3469 [hci0] 89228.476906
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3470 [hci0] 89228.477535
      LE L2CAP: LE Flow Control Credit (0x16) ident 11 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3471 [hci0] 89228.505185
      LE L2CAP: LE Flow Control Credit (0x16) ident 13 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3472 [hci0] 89228.526656
      Channel: 64 len 50 sdu 60 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3c 00 79 4b 00 16 3a 00 05 02 00 00 01 00 8f 00  <.yK..:.........
        8c 05 00 00 00 02 02 00 00 00 ff 02 00 00 00 00  ................
        00 00 00 00 00 01 ff 37 e4 33 02 00 00 00 ff 02  .......7.3......
        00 00                                            ..              
< ACL Data TX: Handle 0 flags 0x00 dlen 16            #3473 [hci0] 89228.526721
      Channel: 64 len 12 sdu 12 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 00 00 00 00 02              ............    
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3474 [hci0] 89228.535929
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3475 [hci0] 89228.536910
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3476 [hci0] 89228.537795
      LE L2CAP: LE Flow Control Credit (0x16) ident 15 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3477 [hci0] 89228.565428
      LE L2CAP: LE Flow Control Credit (0x16) ident 17 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3478 [hci0] 89228.716318
      Channel: 64 len 50 sdu 60 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3c 00 79 4b 00 16 3a 00 05 02 00 00 01 00 8f 00  <.yK..:.........
        88 05 00 00 00 02 04 00 00 00 ff 02 00 00 00 00  ................
        00 00 00 00 00 01 ff 37 e4 33 04 00 00 00 ff 02  .......7.3......
        00 00                                            ..              
< ACL Data TX: Handle 0 flags 0x00 dlen 16            #3479 [hci0] 89228.716382
      Channel: 64 len 12 sdu 12 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 00 00 00 00 02              ............    
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3480 [hci0] 89228.775909
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3481 [hci0] 89228.776905
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3482 [hci0] 89228.778034
      LE L2CAP: LE Flow Control Credit (0x16) ident 19 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3483 [hci0] 89228.805657
      LE L2CAP: LE Flow Control Credit (0x16) ident 21 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 27            #3484 [hci0] 89228.925781
> ACL Data RX: Handle 0 flags 0x01 dlen 12            #3485 [hci0] 89228.927157
      Channel: 64 len 35 sdu 33 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        21 00 7b 49 3a 02 01 ff 06 f8 9b 87 00 cb 5d 00  !.{I:.........].
        00 00 00 fe 80 00 00 00 00 00 00 00 85 c0 ff fe  ................
        06 f8 9b                                         ...             
< ACL Data TX: Handle 0 flags 0x00 dlen 12            #3486 [hci0] 89228.927268
      LE L2CAP: LE Flow Control Credit (0x16) ident 2 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3487 [hci0] 89228.954905
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 18            #3488 [hci0] 89228.986032
      Channel: 64 len 14 sdu 12 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        0c 00 7b 4b 3a 02 85 00 7b b8 00 00 00 00        ..{K:...{.....  
< ACL Data TX: Handle 0 flags 0x00 dlen 12            #3489 [hci0] 89228.986149
      LE L2CAP: LE Flow Control Credit (0x16) ident 3 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3490 [hci0] 89229.014908
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 47            #3491 [hci0] 89229.386277
      Channel: 64 len 43 sdu 41 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        29 00 7b 49 3a 02 01 ff 37 e4 33 87 00 26 49 00  ).{I:...7.3..&I.
        00 00 00 fe 80 00 00 00 00 00 00 f3 a0 b4 ff fe  ................
        37 e4 33 0e 01 2a 83 53 c8 5a 12                 7.3..*.S.Z.     
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3492 [hci0] 89229.405911
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3493 [hci0] 89229.434911
      LE L2CAP: LE Flow Control Credit (0x16) ident 23 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 26            #3494 [hci0] 89229.945033
      Channel: 64 len 22 sdu 20 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        14 00 7b 3b 3a 02 85 00 0a df 00 00 00 00 01 01  ..{;:...........
        00 85 c0 06 f8 9b                                ......          
< ACL Data TX: Handle 0 flags 0x00 dlen 12            #3495 [hci0] 89229.945326
      LE L2CAP: LE Flow Control Credit (0x16) ident 4 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3496 [hci0] 89229.974905
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3497 [hci0] 89230.416574
      Channel: 64 len 50 sdu 80 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        50 00 79 3b 00 16 3a 00 05 02 00 00 01 00 8f 00  P.y;..:.........
        fc 5d 00 00 00 03 04 00 00 00 ff 02 00 00 00 00  .]..............
        00 00 00 00 00 01 ff 00 00 00 04 00 00 00 ff 02  ................
        00 00                                            ..              
< ACL Data TX: Handle 0 flags 0x00 dlen 36            #3498 [hci0] 89230.416621
      Channel: 64 len 32 sdu 32 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 01 ff 37 e4 33 04 00 00 00  .........7.3....
        ff 02 00 00 00 00 00 00 00 00 00 00 00 00 00 02  ................
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3499 [hci0] 89230.425915
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3500 [hci0] 89230.426411
      LE L2CAP: LE Flow Control Credit (0x16) ident 25 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3501 [hci0] 89230.446228
      Channel: 64 len 50 sdu 60 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3c 00 79 3b 00 16 3a 00 05 02 00 00 01 00 8f 00  <.y;..:.........
        e1 e9 00 00 00 02 04 00 00 00 ff 02 00 00 00 00  ................
        00 00 00 00 00 00 00 00 00 fb 04 00 00 00 ff 02  ................
        00 00                                            ..              
< ACL Data TX: Handle 0 flags 0x00 dlen 16            #3502 [hci0] 89230.446270
      Channel: 64 len 12 sdu 12 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 01 ff 00 00 00              ............    
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3503 [hci0] 89230.454901
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3504 [hci0] 89230.456528
      LE L2CAP: LE Flow Control Credit (0x16) ident 27 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3505 [hci0] 89230.456903
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3506 [hci0] 89230.485900
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3507 [hci0] 89230.486667
      LE L2CAP: LE Flow Control Credit (0x16) ident 29 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3508 [hci0] 89230.515529
      LE L2CAP: LE Flow Control Credit (0x16) ident 31 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3509 [hci0] 89230.563832
      Channel: 64 len 50 sdu 168 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        a8 00 6f 3b 09 4c d9 fb f0 14 e9 14 e9 5e fd 00  ..o;.L.......^..
        00 00 00 00 02 00 00 00 02 00 00 01 33 01 33 01  ............3.3.
        34 01 65 01 37 01 33 01 65 01 66 01 66 01 66 01  4.e.7.3.e.f.f.f.
        34 01                                            4.              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3510 [hci0] 89230.563897
      Channel: 64 len 50 sdu 120 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        62 01 30 01 61 01 33 01 66 01 30 01 30 01 30 01  b.0.a.3.f.0.0.0.
        30 01 30 01 30 01 30 01 30 01 30 01 30 01 30 01  0.0.0.0.0.0.0.0.
        30 01 30 01 38 01 65 01 66 03 69 70 36 04 61 72  0.0.8.e.f.ip6.ar
        70 61                                            pa              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3511 [hci0] 89230.563922
      Channel: 64 len 50 sdu 70 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 ff 00 01 0b 72 61 73 70 62 65 72 72 79 70  ......raspberryp
        69 05 6c 6f 63 61 6c 00 00 ff 00 01 c0 5a 00 1c  i.local......Z..
        00 01 00 00 00 78 00 10 fe 80 00 00 00 00 00 00  .....x..........
        f3 a0                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3512 [hci0] 89230.575902
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 24            #3513 [hci0] 89230.575985
      Channel: 64 len 20 sdu 20 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        b4 ff fe 37 e4 33 c0 0c 00 0c 00 01 00 00 00 78  ...7.3.........x
        00 02 c0 5a                                      ...Z            
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3514 [hci0] 89230.576908
      LE L2CAP: LE Flow Control Credit (0x16) ident 33 len 4
        Source CID: 64
        Credits: 3
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3515 [hci0] 89230.604900
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3516 [hci0] 89230.604971
      Channel: 64 len 50 sdu 100 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        64 00 79 3b 00 16 3a 00 05 02 00 00 01 00 8f 00  d.y;..:.........
        f8 4a 00 00 00 04 04 00 00 00 ff 02 00 00 00 00  .J..............
        00 00 00 00 00 00 00 00 00 fb 04 00 00 00 ff 02  ................
        00 00                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3517 [hci0] 89230.606899
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3518 [hci0] 89230.606969
      Channel: 64 len 50 sdu 52 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 01 ff 00 00 00 04 00 00 00  ................
        ff 02 00 00 00 00 00 00 00 00 00 01 ff 37 e4 33  .............7.3
        04 00 00 00 ff 02 00 00 00 00 00 00 00 00 00 00  ................
        00 00                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3519 [hci0] 89230.634899
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 6             #3520 [hci0] 89230.634969
      Channel: 64 len 2 sdu 2 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 02                                            ..              
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3521 [hci0] 89230.635779
      LE L2CAP: LE Flow Control Credit (0x16) ident 35 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3522 [hci0] 89230.636898
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3523 [hci0] 89230.636967
      Channel: 64 len 50 sdu 60 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3c 00 79 3b 00 16 3a 00 05 02 00 00 01 00 8f 00  <.y;..:.........
        e1 e9 00 00 00 02 04 00 00 00 ff 02 00 00 00 00  ................
        00 00 00 00 00 00 00 00 00 fb 04 00 00 00 ff 02  ................
        00 00                                            ..              
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3524 [hci0] 89230.665906
      LE L2CAP: LE Flow Control Credit (0x16) ident 37 len 4
        Source CID: 64
        Credits: 2
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3525 [hci0] 89230.666900
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 16            #3526 [hci0] 89230.666966
      Channel: 64 len 12 sdu 12 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 01 ff 00 00 00              ............    
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3527 [hci0] 89230.667899
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3528 [hci0] 89230.668528
      LE L2CAP: LE Flow Control Credit (0x16) ident 39 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3529 [hci0] 89230.694904
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3530 [hci0] 89230.695898
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3531 [hci0] 89230.696157
      LE L2CAP: LE Flow Control Credit (0x16) ident 41 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3532 [hci0] 89230.725029
      LE L2CAP: LE Flow Control Credit (0x16) ident 43 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3533 [hci0] 89230.814413
      Channel: 64 len 50 sdu 168 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        a8 00 6f 3b 09 4c d9 fb f0 14 e9 14 e9 5e fd 00  ..o;.L.......^..
        00 00 00 00 02 00 00 00 02 00 00 01 33 01 33 01  ............3.3.
        34 01 65 01 37 01 33 01 65 01 66 01 66 01 66 01  4.e.7.3.e.f.f.f.
        34 01                                            4.              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3534 [hci0] 89230.814457
      Channel: 64 len 50 sdu 120 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        62 01 30 01 61 01 33 01 66 01 30 01 30 01 30 01  b.0.a.3.f.0.0.0.
        30 01 30 01 30 01 30 01 30 01 30 01 30 01 30 01  0.0.0.0.0.0.0.0.
        30 01 30 01 38 01 65 01 66 03 69 70 36 04 61 72  0.0.8.e.f.ip6.ar
        70 61                                            pa              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3535 [hci0] 89230.814481
      Channel: 64 len 50 sdu 70 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 ff 00 01 0b 72 61 73 70 62 65 72 72 79 70  ......raspberryp
        69 05 6c 6f 63 61 6c 00 00 ff 00 01 c0 5a 00 1c  i.local......Z..
        00 01 00 00 00 78 00 10 fe 80 00 00 00 00 00 00  .....x..........
        f3 a0                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3536 [hci0] 89230.845902
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 24            #3537 [hci0] 89230.845987
      Channel: 64 len 20 sdu 20 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        b4 ff fe 37 e4 33 c0 0c 00 0c 00 01 00 00 00 78  ...7.3.........x
        00 02 c0 5a                                      ...Z            
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3538 [hci0] 89230.847654
      LE L2CAP: LE Flow Control Credit (0x16) ident 45 len 4
        Source CID: 64
        Credits: 3
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3539 [hci0] 89230.874900
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3540 [hci0] 89230.876898
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3541 [hci0] 89230.904898
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3542 [hci0] 89230.935278
      LE L2CAP: LE Flow Control Credit (0x16) ident 47 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3543 [hci0] 89231.064975
      Channel: 64 len 50 sdu 168 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        a8 00 6f 3b 09 4c d9 fb f0 14 e9 14 e9 5e fd 00  ..o;.L.......^..
        00 00 00 00 02 00 00 00 02 00 00 01 33 01 33 01  ............3.3.
        34 01 65 01 37 01 33 01 65 01 66 01 66 01 66 01  4.e.7.3.e.f.f.f.
        34 01                                            4.              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3544 [hci0] 89231.065022
      Channel: 64 len 50 sdu 120 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        62 01 30 01 61 01 33 01 66 01 30 01 30 01 30 01  b.0.a.3.f.0.0.0.
        30 01 30 01 30 01 30 01 30 01 30 01 30 01 30 01  0.0.0.0.0.0.0.0.
        30 01 30 01 38 01 65 01 66 03 69 70 36 04 61 72  0.0.8.e.f.ip6.ar
        70 61                                            pa              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3545 [hci0] 89231.065051
      Channel: 64 len 50 sdu 70 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 ff 00 01 0b 72 61 73 70 62 65 72 72 79 70  ......raspberryp
        69 05 6c 6f 63 61 6c 00 00 ff 00 01 c0 5a 00 1c  i.local......Z..
        00 01 00 00 00 78 00 10 fe 80 00 00 00 00 00 00  .....x..........
        f3 a0                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3546 [hci0] 89231.085903
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 24            #3547 [hci0] 89231.085983
      Channel: 64 len 20 sdu 20 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        b4 ff fe 37 e4 33 c0 0c 00 0c 00 01 00 00 00 78  ...7.3.........x
        00 02 c0 5a                                      ...Z            
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3548 [hci0] 89231.087907
      LE L2CAP: LE Flow Control Credit (0x16) ident 49 len 4
        Source CID: 64
        Credits: 3
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3549 [hci0] 89231.114899
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3550 [hci0] 89231.116897
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3551 [hci0] 89231.144897
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3552 [hci0] 89231.205530
      LE L2CAP: LE Flow Control Credit (0x16) ident 51 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3553 [hci0] 89231.265599
      Channel: 64 len 50 sdu 156 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        9c 00 6f 3b 09 4c d9 fb f0 14 e9 14 e9 43 80 00  ..o;.L.......C..
        00 84 00 00 00 00 02 00 00 00 00 01 33 01 33 01  ............3.3.
        34 01 65 01 37 01 33 01 65 01 66 01 66 01 66 01  4.e.7.3.e.f.f.f.
        34 01                                            4.              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3554 [hci0] 89231.265660
      Channel: 64 len 50 sdu 108 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        62 01 30 01 61 01 33 01 66 01 30 01 30 01 30 01  b.0.a.3.f.0.0.0.
        30 01 30 01 30 01 30 01 30 01 30 01 30 01 30 01  0.0.0.0.0.0.0.0.
        30 01 30 01 38 01 65 01 66 03 69 70 36 04 61 72  0.0.8.e.f.ip6.ar
        70 61                                            pa              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3555 [hci0] 89231.265686
      Channel: 64 len 50 sdu 58 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 0c 80 01 00 00 00 78 00 13 0b 72 61 73 70  ........x...rasp
        62 65 72 72 79 70 69 05 6c 6f 63 61 6c 00 c0 60  berrypi.local..`
        00 1c 80 01 00 00 00 78 00 10 fe 80 00 00 00 00  .......x........
        00 00                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3556 [hci0] 89231.295899
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 12            #3557 [hci0] 89231.295986
      Channel: 64 len 8 sdu 8 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        f3 a0 b4 ff fe 37 e4 33                          .....7.3        
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3558 [hci0] 89231.296908
      LE L2CAP: LE Flow Control Credit (0x16) ident 53 len 4
        Source CID: 64
        Credits: 3
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3559 [hci0] 89231.325897
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3560 [hci0] 89231.326899
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3561 [hci0] 89231.354897
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3562 [hci0] 89231.385777
      LE L2CAP: LE Flow Control Credit (0x16) ident 55 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3563 [hci0] 89232.362103
      Channel: 64 len 50 sdu 156 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        9c 00 6f 3b 09 4c d9 fb f0 14 e9 14 e9 43 80 00  ..o;.L.......C..
        00 84 00 00 00 00 02 00 00 00 00 01 33 01 33 01  ............3.3.
        34 01 65 01 37 01 33 01 65 01 66 01 66 01 66 01  4.e.7.3.e.f.f.f.
        34 01                                            4.              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3564 [hci0] 89232.362159
      Channel: 64 len 50 sdu 108 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        62 01 30 01 61 01 33 01 66 01 30 01 30 01 30 01  b.0.a.3.f.0.0.0.
        30 01 30 01 30 01 30 01 30 01 30 01 30 01 30 01  0.0.0.0.0.0.0.0.
        30 01 30 01 38 01 65 01 66 03 69 70 36 04 61 72  0.0.8.e.f.ip6.ar
        70 61                                            pa              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3565 [hci0] 89232.362188
      Channel: 64 len 50 sdu 58 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 0c 80 01 00 00 00 78 00 13 0b 72 61 73 70  ........x...rasp
        62 65 72 72 79 70 69 05 6c 6f 63 61 6c 00 c0 60  berrypi.local..`
        00 1c 80 01 00 00 00 78 00 10 fe 80 00 00 00 00  .......x........
        00 00                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3566 [hci0] 89232.375902
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 12            #3567 [hci0] 89232.375983
      Channel: 64 len 8 sdu 8 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        f3 a0 b4 ff fe 37 e4 33                          .....7.3        
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3568 [hci0] 89232.377153
      LE L2CAP: LE Flow Control Credit (0x16) ident 57 len 4
        Source CID: 64
        Credits: 3
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3569 [hci0] 89232.404895
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3570 [hci0] 89232.406895
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3571 [hci0] 89232.434894
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3572 [hci0] 89232.464774
      LE L2CAP: LE Flow Control Credit (0x16) ident 59 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3573 [hci0] 89234.460616
      Channel: 64 len 50 sdu 156 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        9c 00 6f 3b 09 4c d9 fb f0 14 e9 14 e9 43 80 00  ..o;.L.......C..
        00 84 00 00 00 00 02 00 00 00 00 01 33 01 33 01  ............3.3.
        34 01 65 01 37 01 33 01 65 01 66 01 66 01 66 01  4.e.7.3.e.f.f.f.
        34 01                                            4.              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3574 [hci0] 89234.460666
      Channel: 64 len 50 sdu 108 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        62 01 30 01 61 01 33 01 66 01 30 01 30 01 30 01  b.0.a.3.f.0.0.0.
        30 01 30 01 30 01 30 01 30 01 30 01 30 01 30 01  0.0.0.0.0.0.0.0.
        30 01 30 01 38 01 65 01 66 03 69 70 36 04 61 72  0.0.8.e.f.ip6.ar
        70 61                                            pa              
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3575 [hci0] 89234.460692
      Channel: 64 len 50 sdu 58 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 0c 80 01 00 00 00 78 00 13 0b 72 61 73 70  ........x...rasp
        62 65 72 72 79 70 69 05 6c 6f 63 61 6c 00 c0 60  berrypi.local..`
        00 1c 80 01 00 00 00 78 00 10 fe 80 00 00 00 00  .......x........
        00 00                                            ..              
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3576 [hci0] 89234.475893
        Num handles: 1
        Handle: 0
        Count: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 12            #3577 [hci0] 89234.475990
      Channel: 64 len 8 sdu 8 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        f3 a0 b4 ff fe 37 e4 33                          .....7.3        
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3578 [hci0] 89234.477397
      LE L2CAP: LE Flow Control Credit (0x16) ident 61 len 4
        Source CID: 64
        Credits: 3
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3579 [hci0] 89234.504900
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3580 [hci0] 89234.506888
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3581 [hci0] 89234.534888
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3582 [hci0] 89234.565018
      LE L2CAP: LE Flow Control Credit (0x16) ident 63 len 4
        Source CID: 64
        Credits: 1
< ACL Data TX: Handle 0 flags 0x00 dlen 54            #3583 [hci0] 89245.545023
      Channel: 64 len 50 sdu 63 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        3f 00 6b 3b 01 90 f5 3a 01 86 00 22 07 40 00 07  ?.k;...:...".@..
        08 00 00 00 00 00 00 00 00 03 04 40 60 00 01 51  ...........@`..Q
        80 00 00 38 40 00 00 00 00 20 01 0d b8 00 00 00  ...8@.... ......
        00 00                                            ..              
< ACL Data TX: Handle 0 flags 0x00 dlen 19            #3584 [hci0] 89245.545076
      Channel: 64 len 15 sdu 15 [PSM 35 mode LE Flow Control (0x80)] {chan 1}
        00 00 00 00 00 00 00 01 01 f3 a0 b4 37 e4 33     ............7.3 
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3585 [hci0] 89245.574865
        Num handles: 1
        Handle: 0
        Count: 1
> HCI Event: Number of Completed Pac.. (0x13) plen 5  #3586 [hci0] 89245.575861
        Num handles: 1
        Handle: 0
        Count: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3587 [hci0] 89245.576365
      LE L2CAP: LE Flow Control Credit (0x16) ident 65 len 4
        Source CID: 64
        Credits: 1
> ACL Data RX: Handle 0 flags 0x02 dlen 12            #3588 [hci0] 89245.605240
      LE L2CAP: LE Flow Control Credit (0x16) ident 67 len 4
        Source CID: 64
        Credits: 1
> HCI Event: Disconnect Complete (0x05) plen 4        #3589 [hci0] 89264.382819
        Status: Success (0x00)
        Handle: 0
        Reason: LMP Response Timeout / LL Response Timeout (0x22)
@ MGMT Event: Device Disconnected (0x000c) plen 8  {0x0002} [hci0] 89264.382889
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Reason: Unspecified (0x00)
@ MGMT Event: Device Disconnected (0x000c) plen 8  {0x0001} [hci0] 89264.382889
        LE Address: 00:85:C0:06:F8:9B (OUI 00-85-C0)
        Reason: Unspecified (0x00)

With all of those samples, I can reproduce the problem.
Regards.

@joerchan
Copy link
Contributor

joerchan commented Nov 4, 2020

@kasapreyizz Can you check the nRF5 SDK sample. I suspect these two events are not replied to:
BLE_GAP_EVT_DATA_LENGTH_UPDATE_REQUEST
BLE_GAP_EVT_PHY_UPDATE_REQUEST

The should be replied to with sd_ble_gap_data_length_update and sd_ble_gap_phy_update respectively:

        case BLE_GAP_EVT_DATA_LENGTH_UPDATE_REQUEST:
        {
            ble_gap_data_length_params_t dl_params;

            // Clearing the struct will effectively set members to @ref BLE_GAP_DATA_LENGTH_AUTO.
            memset(&dl_params, 0, sizeof(ble_gap_data_length_params_t));
            UNUSED_RETURN_VALUE(sd_ble_gap_data_length_update(p_ble_evt->evt.gap_evt.conn_handle, &dl_params, NULL));
            break;
        }

        case BLE_GAP_EVT_PHY_UPDATE_REQUEST:
        {
            ble_gap_phys_t const phys =
            {
                .rx_phys = BLE_GAP_PHY_AUTO,
                .tx_phys = BLE_GAP_PHY_AUTO,
            };

            UNUSED_RETURN_VALUE(sd_ble_gap_phy_update(p_ble_evt->evt.gap_evt.conn_handle, &phys);
            break;
        }

@kasapreyizz
Copy link
Author

@joerchan My SDK sample replies those events but my problem is stay still. I double checked my connection parameters and they are completely same. Actually all of the nrf sdk examples produces that error. But I suspect PDU sizes. Does any minimal requirements in PDU sizes in extended scanning and enhanced connections? Or could my problem may rooted from somethink else ? I will try making enhanced connection with an Android phone and see if it's disconnects too, If I succeed in doing this.

Thanks in advance.

@joerchan
Copy link
Contributor

joerchan commented Nov 6, 2020

@kasapreyizz Ok, since this is related the nRF5 SDK samples then you should open up an issue on nordic devzone instead, and close this one.
They will help you out there.

@kasapreyizz
Copy link
Author

@joerchan If I disconnect from an Android device too via my hci_usb sample, that proves that problem is at the Zephyr / nrfsdk side actually. If it wouldn't be a problem, could this issue still open while I am testing that case ?

@carlescufi
Copy link
Member

@kasapreyizz as @joerchan said, you are using the SoftDevice Controller, which means this is not applicable for the Zephyr Project. Please open an issue in https://devzone.nordicsemi.com/

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
area: Bluetooth bug The issue is a bug, or the PR is fixing a bug platform: nRF Nordic nRFx
Projects
None yet
Development

No branches or pull requests

4 participants