bt_att: att_timeout: ATT Timeout error after subscribing succesfully.

[Martdur]

Hello, 

I get the bt_att: att_timeout, on the central side after subscribing to some characteristics of a cycling power sensor. It work for other sensors like cycling cadence and speed.

It's seems like the error occurs on notification.

I've tried differents connexion parameters but here is my current config :

 

#define BLE_MINIMUM_RSSI    40
#define SCAN_INTERVAL       0x0640 /* 1000 ms */
#define SCAN_WINDOW         0x0030 /* 30 ms */
#define INIT_INTERVAL       0x0010 /* 10 ms */
#define INIT_WINDOW         0x0010 /* 10 ms */
#define CONN_INTERVAL       0x0320 /* 1000 ms */
#define CONN_LATENCY        0
#define PEER_NAME_LEN_MAX   30
#define CONN_TIMEOUT  MIN(MAX((CONN_INTERVAL * 125 * \
			       MAX(CONFIG_BT_MAX_CONN, 6) / 1000), 10), 3200)
			       
const struct bt_le_conn_param BleCentralImpl::conn_param = {
	.interval_min = 0x40 /*CONN_INTERVAL*/,
	.interval_max = CONN_INTERVAL,
	.latency = CONN_LATENCY,
	.timeout = CONN_TIMEOUT,
};

Here the log (The error happens at the end of the file): 

*** Booting Zephyr OS build v3.2.99-ncs1 ***
[00:00:00.562,530] <inf> TIMESTAMP_CLOCK: Init: RTC online!
[00:00:00.571,044] <inf> TIMESTAMP_CLOCK: print_tm_helper: 08/05/2022
[00:00:00.578,125] <inf> TIMESTAMP_CLOCK: print_tm_helper: 16:00:00
[00:00:00.585,052] <inf> main: main: Concentrateur starting
[00:00:00.591,217] <inf> littlefs: littlefs_mount: LittleFS version 2.5, disk version 2.0
[00:00:00.601,440] <inf> littlefs: littlefs_mount: FS at mx25r6435f@0:0x0 is 2047 0x1000-byte blocks with 512 cycle
[00:00:00.612,609] <inf> littlefs: littlefs_mount: sizes: rd 16 ; pr 16 ; ca 64 ; la 32
[00:00:00.654,785] <inf> littlefs: littlefs_mount: /lfs_ext_flash mounted
[00:00:00.662,170] <inf> FILESYSTEM: MountImpl: /lfs_ext_flash mount: 0
[00:00:00.669,403] <inf> TestBle: BleFileTest: We delete All the Files
[00:00:00.686,340] <inf> FILESYSTEM: lsdirImpl: Listing dir /lfs_ext_flash ...
[00:00:00.721,954] <inf> FILESYSTEM: lsdirImpl: [FILE] brytonCadence.io (size = 6)
[00:00:00.745,544] <inf> FILESYSTEM: lsdirImpl: [FILE] brytonSpeed.io (size = 6)
[00:00:00.778,289] <inf> FILESYSTEM: DeleteAllFilesInDirImpl: Listing dir /lfs_ext_flash ...
[00:00:00.815,216] <inf> FILESYSTEM: DeleteAllFilesInDirImpl: We delete [FILE] /lfs_ext_flash/brytonCadence.io (size = 6)
[00:00:00.856,262] <inf> FILESYSTEM: DeleteAllFilesInDirImpl: We delete [FILE] /lfs_ext_flash/brytonSpeed.io (size = 6)
[00:00:00.896,484] <inf> TestBle: BleFileTest: We Create Data Files
[00:00:01.017,150] <inf> FILESYSTEM: lsdirImpl: Listing dir /lfs_ext_flash ...
[00:00:01.053,527] <inf> FILESYSTEM: lsdirImpl: [FILE] brytonCadence.io (size = 6)
[00:00:01.077,117] <inf> FILESYSTEM: lsdirImpl: [FILE] brytonSpeed.io (size = 6)
[00:00:01.099,975] <inf> TestBle: BleFileTest: Initialisation of ble central
[00:00:01.122,406] <inf> BLE: Init: Starting Bluetooth Central BAS
[00:00:01.157,226] <inf> bt_hci_core: hci_vs_init: HW Platform: Nordic Semiconductor (0x0002)
[00:00:01.181,182] <inf> bt_hci_core: hci_vs_init: HW Variant: nRF53x (0x0003)
[00:00:01.203,826] <inf> bt_hci_core: hci_vs_init: Firmware: Standard Bluetooth controller (0x00) Version 109.16784 Build 2917677098
[00:00:01.233,459] <inf> bt_hci_core: bt_dev_show_info: Identity: E2:D6:3E:AC:19:7E (random)
[00:00:01.257,385] <inf> bt_hci_core: bt_dev_show_info: HCI: version 5.3 (0x0c) revision 0x21fa, manufacturer 0x0059
[00:00:01.283,386] <inf> bt_hci_core: bt_dev_show_info: LMP: version 5.3 (0x0c) subver 0x21fa
[00:00:01.307,373] <inf> BLE: Init: Bluetooth initialized
[00:00:01.328,155] <inf> BLE: RegisterSensor: Device we wanna add : 0xC156D04CBFF300
[00:00:01.351,348] <inf> BLE: RegisterSensor: Device added : 0xC156D04CBFF300
[00:00:01.373,931] <inf> BLE: RegisterSensor: Device we wanna add : 0x6C58411374EF00
[00:00:01.397,094] <inf> BLE: RegisterSensor: Device added : 0x6C58411374EF00
[00:00:01.419,708] <inf> BLE: RegisterSensor: Device we wanna add : 0x6CB8F09478D200
[00:00:01.442,901] <inf> BLE: RegisterSensor: Device added : 0x6CB8F09478D200
[00:00:01.468,261] <inf> BLE: Scan: Scanning successfully started
[00:00:11.477,966] <inf> BLE: OnDeviceFoundCb: Device found : [] D2:78:94:F0:B8:6C (random) (RSSI -40)
[00:00:11.503,234] <inf> BLE: OnDeviceFoundCb: Trying to connect to : [] D2:78:94:F0:B8:6C (random) (RSSI -40)

Data at 0: 0xe3
Data at 1: 0x76
Data at 2: 0x0b
Data at 3: 0x44
Data at 4: 0x32
Data at 5: 0x01
Data at 6: 0x00
Data at 7: 0x00
Data at 8: 0xa4
END 
[00:00:11.874,938] <inf> BLE: OnConnectCb: Connected (0): D2:78:94:F0:B8:6C (random)
[00:00:11.900,604] <inf> BLE: Scan: Scanning successfully started
[00:00:11.922,088] <inf> BLE: mtu_exchange: MTU (0): 23
Exchange pending...[00:00:13.565,765] <inf> BLE: OnMtuExchangeCb: MTU exchange 0 successful (23)
[00:00:17.566,650] <inf> BLE: le_param_req: LE conn param req: D2:78:94:F0:B8:6C (random) int (0x0020, 0x00a0) lat 4 to 500
[00:00:24.565,734] <inf> BLE: le_param_updated: LE conn param updated: D2:78:94:F0:B8:6C (random) int 0x00a0 lat 4 to 500
[00:00:26.565,856] <inf> BLE: OnSecurityChangeCb: Security changed: D2:78:94:F0:B8:6C (random) level 2
[00:00:26.590,637] <dbg> BLE_APP_DISCOVERY: GattDiscover: Start Discovery
[00:00:26.613,281] <err> BLE: StopScan: Scan failed to stop.
[00:00:26.634,613] <dbg> BLE_APP_DISCOVERY: GattDiscover: You provided a sensor
[00:00:26.965,881] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 1
[00:00:26.987,915] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 0, handle: 1
[00:00:27.010,589] <dbg> bt_gatt_dm: discovery_process_service: Service detected, handles range: <2, 7>
[00:00:27.035,461] <dbg> bt_gatt_dm: discovery_process_service: Starting descriptors discovery
[00:00:27.365,875] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 2
[00:00:27.387,908] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 1, handle: 2
[00:00:27.410,552] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 3
[00:00:27.432,586] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 2, handle: 3
[00:00:27.455,230] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 4
[00:00:27.477,264] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 3, handle: 4
[00:00:27.499,908] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 5
[00:00:27.521,942] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 4, handle: 5
[00:00:27.544,586] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 6
[00:00:27.566,589] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 5, handle: 6
[00:00:27.967,529] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 7
[00:00:27.989,562] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 6, handle: 7
[00:00:28.012,237] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:00:28.034,240] <dbg> bt_gatt_dm: discovery_process_attribute: Starting characteristic discovery
[00:00:28.166,412] <inf> BLE: OnPairingCompleteCb: Pairing confirmed to D2:78:94:F0:B8:6C (random), bonded: 1
[00:00:28.365,905] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 2
[00:00:28.387,939] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 4
[00:00:28.409,973] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 6
[00:00:28.765,747] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:00:28.787,750] <dbg> bt_gatt_dm: discovery_complete: Discovery complete.
[00:00:28.810,211] <inf> BLE_APP_DISCOVERY: OnDiscoveryCompletedCb: The discovery procedure succeeded
[00:00:28.834,869] <dbg> bt_gatt_dm: svc_attr_memory_release: Attr memory release
[00:00:29.165,924] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 8
[00:00:29.187,957] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 0, handle: 8
[00:00:29.210,632] <dbg> bt_gatt_dm: discovery_process_service: Service detected, handles range: <9, 11>
[00:00:29.235,595] <dbg> bt_gatt_dm: discovery_process_service: Starting descriptors discovery
[00:00:29.565,826] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 9
[00:00:29.587,860] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 1, handle: 9
[00:00:29.610,534] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 10
[00:00:29.632,659] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 2, handle: 10
[00:00:29.655,426] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 11
[00:00:29.677,581] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 3, handle: 11
[00:00:29.700,317] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:00:29.722,351] <dbg> bt_gatt_dm: discovery_process_attribute: Starting characteristic discovery
[00:00:29.965,759] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 9
[00:00:30.365,722] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:00:30.387,756] <dbg> bt_gatt_dm: discovery_complete: Discovery complete.
[00:00:30.410,186] <inf> BLE_APP_DISCOVERY: OnDiscoveryCompletedCb: The discovery procedure succeeded
[00:00:30.434,814] <dbg> bt_gatt_dm: svc_attr_memory_release: Attr memory release
[00:00:30.765,808] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 12
[00:00:30.787,933] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 0, handle: 12
[00:00:30.810,729] <dbg> bt_gatt_dm: discovery_process_service: Service detected, handles range: <13, 24>
[00:00:30.835,754] <dbg> bt_gatt_dm: discovery_process_service: Starting descriptors discovery
[00:00:31.165,893] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 13
[00:00:31.188,018] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 1, handle: 13
[00:00:31.210,723] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 14
[00:00:31.232,849] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 2, handle: 14
[00:00:31.255,584] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 15
[00:00:31.277,679] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 3, handle: 15
[00:00:31.300,415] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 16
[00:00:31.322,540] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 4, handle: 16
[00:00:31.345,275] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 17
[00:00:31.367,370] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 5, handle: 17
[00:00:32.565,887] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 18
[00:00:32.588,012] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 6, handle: 18
[00:00:32.610,717] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 19
[00:00:32.632,843] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 7, handle: 19
[00:00:32.655,548] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 20
[00:00:32.677,673] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 8, handle: 20
[00:00:32.700,408] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 21
[00:00:32.722,503] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 9, handle: 21
[00:00:32.745,239] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 22
[00:00:32.767,333] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 10, handle: 22
[00:00:34.165,771] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 23
[00:00:34.187,896] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 11, handle: 23
[00:00:34.210,723] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 24
[00:00:34.232,818] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 12, handle: 24
[00:00:34.255,615] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:00:34.277,587] <dbg> bt_gatt_dm: discovery_process_attribute: Starting characteristic discovery
[00:00:34.565,887] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 13
[00:00:34.588,012] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 15
[00:00:34.610,137] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 17
[00:00:35.165,893] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 19
[00:00:35.188,018] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 21
[00:00:35.210,083] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 23
[00:00:35.565,734] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:00:35.587,738] <dbg> bt_gatt_dm: discovery_complete: Discovery complete.
[00:00:35.610,168] <inf> BLE_APP_DISCOVERY: OnDiscoveryCompletedCb: The discovery procedure succeeded
[00:00:35.634,765] <dbg> bt_gatt_dm: svc_attr_memory_release: Attr memory release
[00:00:36.165,771] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 25
[00:00:36.187,896] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 0, handle: 25
[00:00:36.210,662] <dbg> bt_gatt_dm: discovery_process_service: Service detected, handles range: <26, 35>
[00:00:36.235,687] <dbg> bt_gatt_dm: discovery_process_service: Starting descriptors discovery
[00:00:36.565,887] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 26
[00:00:36.588,012] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 1, handle: 26
[00:00:36.610,748] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 27
[00:00:36.632,843] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 2, handle: 27
[00:00:36.655,578] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 28
[00:00:36.677,703] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 3, handle: 28
[00:00:36.700,439] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 29
[00:00:36.722,534] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 4, handle: 29
[00:00:36.745,269] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 30
[00:00:36.767,395] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 5, handle: 30
[00:00:38.165,893] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 31
[00:00:38.187,988] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 6, handle: 31
[00:00:38.210,693] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 32
[00:00:38.232,788] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 7, handle: 32
[00:00:38.255,493] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 33
[00:00:38.277,587] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 8, handle: 33
[00:00:38.300,292] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 34
[00:00:38.322,387] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 9, handle: 34
[00:00:38.345,123] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 35
[00:00:38.367,218] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 10, handle: 35
[00:00:38.390,014] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:00:38.412,017] <dbg> bt_gatt_dm: discovery_process_attribute: Starting characteristic discovery
[00:00:39.565,887] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 26
[00:00:39.588,012] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 29
[00:00:39.610,137] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 31
[00:00:40.165,771] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 33
[00:00:40.565,734] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:00:40.587,738] <dbg> bt_gatt_dm: discovery_complete: Discovery complete.
[00:00:40.610,168] <inf> BLE_APP_DISCOVERY: OnDiscoveryCompletedCb: The discovery procedure succeeded
[00:00:40.634,796] <dbg> CPWR: bt_cpwr_handles_assign: Getting handles from CPWR service.
[00:00:40.658,386] <dbg> CPWR: bt_cpwr_handles_assign: Found handle for CPWR Measurement characteristic.
[00:00:40.683,288] <dbg> CPWR: bt_cpwr_handles_assign: Found handle for CCC of CPWR Measurement CCC characteristic.
[00:00:40.709,136] <dbg> CPWR: bt_cpwr_subscribe_receive: Subscribe: val: 27, ccc: 28
[00:00:40.732,574] <dbg> CPWR: bt_cpwr_subscribe_receive: [SUBSCRIBED]
[00:00:40.754,486] <dbg> bt_gatt_dm: svc_attr_memory_release: Attr memory release
[00:01:11.765,747] <err> bt_att: att_timeout: ATT Timeout
[00:01:11.786,834] <inf> CPWR: notify_process: Notifications disabled.
[00:01:11.808,746] <inf> APP_CPWR_CLIENT: unsubscribed_cpwr_measurement_cb: Unsubcribed ! 
[00:01:11.832,427] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:01:11.854,431] <dbg> bt_gatt_dm: discovery_complete_not_found: Discover complete. No service found.
[00:01:11.879,241] <dbg> bt_gatt_dm: svc_attr_memory_release: Attr memory release
[00:01:11.902,130] <inf> BLE_APP_DISCOVERY: OnDiscoveryServiceNotFoundCb: The service discovery procedure has ended or no services could found during the discover
[00:01:11.932,952] <inf> BLE: Scan: Scanning successfully started

And the extended log :

*** Booting Zephyr OS build v3.2.99-ncs1 ***
[00:00:00.579,040] <inf> TIMESTAMP_CLOCK: Init: RTC online!
[00:00:00.587,554] <inf> TIMESTAMP_CLOCK: print_tm_helper: 08/05/2022
[00:00:00.594,665] <inf> TIMESTAMP_CLOCK: print_tm_helper: 16:00:00
[00:00:00.601,623] <inf> main: main: Concentrateur starting
[00:00:00.607,788] <inf> littlefs: littlefs_mount: LittleFS version 2.5, disk version 2.0
[00:00:00.618,041] <inf> littlefs: littlefs_mount: FS at mx25r6435f@0:0x0 is 2047 0x1000-byte blocks with 512 cycle
[00:00:00.629,211] <inf> littlefs: littlefs_mount: sizes: rd 16 ; pr 16 ; ca 64 ; la 32
[00:00:00.664,978] <inf> littlefs: littlefs_mount: /lfs_ext_flash mounted
[00:00:00.672,363] <inf> FILESYSTEM: MountImpl: /lfs_ext_flash mount: 0
[00:00:00.679,595] <inf> TestBle: BleFileTest: We delete All the Files
[00:00:00.694,763] <inf> FILESYSTEM: lsdirImpl: Listing dir /lfs_ext_flash ...
[00:00:00.728,088] <inf> FILESYSTEM: lsdirImpl: [FILE] brytonCadence.io (size = 6)
[00:00:00.751,708] <inf> FILESYSTEM: lsdirImpl: [FILE] brytonSpeed.io (size = 6)
[00:00:00.782,714] <inf> FILESYSTEM: DeleteAllFilesInDirImpl: Listing dir /lfs_ext_flash ...
[00:00:00.817,291] <inf> FILESYSTEM: DeleteAllFilesInDirImpl: We delete [FILE] /lfs_ext_flash/brytonCadence.io (size = 6)
[00:00:00.855,834] <inf> FILESYSTEM: DeleteAllFilesInDirImpl: We delete [FILE] /lfs_ext_flash/brytonSpeed.io (size = 6)
[00:00:00.893,554] <inf> TestBle: BleFileTest: We Create Data Files
[00:00:00.997,131] <inf> FILESYSTEM: lsdirImpl: Listing dir /lfs_ext_flash ...
[00:00:01.031,188] <inf> FILESYSTEM: lsdirImpl: [FILE] brytonCadence.io (size = 6)
[00:00:01.054,779] <inf> FILESYSTEM: lsdirImpl: [FILE] brytonSpeed.io (size = 6)
[00:00:01.077,667] <inf> TestBle: BleFileTest: Initialisation of ble central
[00:00:01.100,097] <inf> BLE: Init: Starting Bluetooth Central BAS
[00:00:01.134,948] <inf> bt_hci_core: hci_vs_init: HW Platform: Nordic Semiconductor (0x0002)
[00:00:01.158,935] <inf> bt_hci_core: hci_vs_init: HW Variant: nRF53x (0x0003)
[00:00:01.181,610] <inf> bt_hci_core: hci_vs_init: Firmware: Standard Bluetooth controller (0x00) Version 109.16784 Build 2917677098
[00:00:01.211,242] <inf> bt_hci_core: bt_dev_show_info: Identity: E2:D6:3E:AC:19:7E (random)
[00:00:01.235,198] <inf> bt_hci_core: bt_dev_show_info: HCI: version 5.3 (0x0c) revision 0x21fa, manufacturer 0x0059
[00:00:01.261,230] <inf> bt_hci_core: bt_dev_show_info: LMP: version 5.3 (0x0c) subver 0x21fa
[00:00:01.285,217] <inf> BLE: Init: Bluetooth initialized
[00:00:01.306,030] <inf> BLE: RegisterSensor: Device we wanna add : 0xC156D04CBFF300
[00:00:01.329,223] <inf> BLE: RegisterSensor: Device added : 0xC156D04CBFF300
[00:00:01.351,837] <inf> BLE: RegisterSensor: Device we wanna add : 0x6C58411374EF00
[00:00:01.375,030] <inf> BLE: RegisterSensor: Device added : 0x6C58411374EF00
[00:00:01.397,644] <inf> BLE: RegisterSensor: Device we wanna add : 0x6CB8F09478D200
[00:00:01.420,837] <inf> BLE: RegisterSensor: Device added : 0x6CB8F09478D200
[00:00:01.446,228] <inf> BLE: Scan: Scanning successfully started
[00:10:01.467,834] <inf> TestBle: BleFileTest: Disconnect all devices
[00:10:01.490,112] <err> BLE: StopScan: Scan failed to stop.
[00:10:01.511,444] <inf> BLE: DisconnectAll: Disconnecting all...
[00:10:01.532,897] <inf> BLE: DisconnectAll: All OnDisconnectCb.
[00:10:01.554,290] <inf> BLE: StopScan: Scanning successfully stopped
[00:10:01.576,141] <inf> TestBle: BleFileTest: Printing all data from cadence
00f3bf4cd056
[00:10:01.729,187] <inf> TestBle: BleFileTest: Printing all data from speed
00d27894f0b8
ÿÀ>Àù€€ø[00:00:00.384,429] <dbg> nrf5340dk_nrf5340_cpuapp: remoteproc_mgr_boot: Network MCU released.
*** Booting Zephyr OS build v3.2.99-ncs1 ***
[00:00:00.552,276] <inf> TIMESTAMP_CLOCK: Init: RTC online!
[00:00:00.560,791] <inf> TIMESTAMP_CLOCK: print_tm_helper: 08/05/2022
[00:00:00.567,871] <inf> TIMESTAMP_CLOCK: print_tm_helper: 16:00:00
[00:00:00.574,798] <inf> main: main: Concentrateur starting
[00:00:00.580,963] <inf> littlefs: littlefs_mount: LittleFS version 2.5, disk version 2.0
[00:00:00.591,217] <inf> littlefs: littlefs_mount: FS at mx25r6435f@0:0x0 is 2047 0x1000-byte blocks with 512 cycle
[00:00:00.602,355] <inf> littlefs: littlefs_mount: sizes: rd 16 ; pr 16 ; ca 64 ; la 32
[00:00:00.639,953] <inf> littlefs: littlefs_mount: /lfs_ext_flash mounted
[00:00:00.647,369] <inf> FILESYSTEM: MountImpl: /lfs_ext_flash mount: 0
[00:00:00.654,602] <inf> TestBle: BleFileTest: We delete All the Files
[00:00:00.670,349] <inf> FILESYSTEM: lsdirImpl: Listing dir /lfs_ext_flash ...
[00:00:00.704,406] <inf> FILESYSTEM: lsdirImpl: [FILE] brytonCadence.io (size = 6)
[00:00:00.727,966] <inf> FILESYSTEM: lsdirImpl: [FILE] brytonSpeed.io (size = 6)
[00:00:00.759,582] <inf> FILESYSTEM: DeleteAllFilesInDirImpl: Listing dir /lfs_ext_flash ...
[00:00:00.794,891] <inf> FILESYSTEM: DeleteAllFilesInDirImpl: We delete [FILE] /lfs_ext_flash/brytonCadence.io (size = 6)
[00:00:00.834,320] <inf> FILESYSTEM: DeleteAllFilesInDirImpl: We delete [FILE] /lfs_ext_flash/brytonSpeed.io (size = 6)
[00:00:00.872,924] <inf> TestBle: BleFileTest: We Create Data Files
[00:00:00.982,421] <inf> FILESYSTEM: lsdirImpl: Listing dir /lfs_ext_flash ...
[00:00:01.017,089] <inf> FILESYSTEM: lsdirImpl: [FILE] brytonCadence.io (size = 6)
[00:00:01.040,679] <inf> FILESYSTEM: lsdirImpl: [FILE] brytonSpeed.io (size = 6)
[00:00:01.063,537] <inf> TestBle: BleFileTest: Initialisation of ble central
[00:00:01.085,998] <inf> BLE: Init: Starting Bluetooth Central BAS
[00:00:01.120,788] <inf> bt_hci_core: hci_vs_init: HW Platform: Nordic Semiconductor (0x0002)
[00:00:01.144,775] <inf> bt_hci_core: hci_vs_init: HW Variant: nRF53x (0x0003)
[00:00:01.167,419] <inf> bt_hci_core: hci_vs_init: Firmware: Standard Bluetooth controller (0x00) Version 109.16784 Build 2917677098
[00:00:01.197,082] <inf> bt_hci_core: bt_dev_show_info: Identity: E2:D6:3E:AC:19:7E (random)
[00:00:01.221,008] <inf> bt_hci_core: bt_dev_show_info: HCI: version 5.3 (0x0c) revision 0x21fa, manufacturer 0x0059
[00:00:01.247,009] <inf> bt_hci_core: bt_dev_show_info: LMP: version 5.3 (0x0c) subver 0x21fa
[00:00:01.270,996] <inf> BLE: Init: Bluetooth initialized
[00:00:01.291,809] <inf> BLE: RegisterSensor: Device we wanna add : 0xC156D04CBFF300
[00:00:01.315,002] <inf> BLE: RegisterSensor: Device added : 0xC156D04CBFF300
[00:00:01.337,585] <inf> BLE: RegisterSensor: Device we wanna add : 0x6C58411374EF00
[00:00:01.360,778] <inf> BLE: RegisterSensor: Device added : 0x6C58411374EF00
[00:00:01.383,392] <inf> BLE: RegisterSensor: Device we wanna add : 0x6CB8F09478D200
[00:00:01.406,555] <inf> BLE: RegisterSensor: Device added : 0x6CB8F09478D200
[00:00:01.431,945] <inf> BLE: Scan: Scanning successfully started
[00:00:46.448,760] <inf> BLE: OnDeviceFoundCb: Device found : [] D2:78:94:F0:B8:6C (random) (RSSI -38)
[00:00:46.474,029] <inf> BLE: OnDeviceFoundCb: Trying to connect to : [] D2:78:94:F0:B8:6C (random) (RSSI -38)
[00:00:46.533,386] <dbg> bt_att: bt_att_accept: conn 0x20009fe0 handle 0
[00:00:46.555,572] <dbg> bt_att: att_chan_attach: att 0x20027aec chan 0x20021d20 flags 0
[00:00:46.579,162] <dbg> bt_att: bt_att_connected: chan 0x20021d24 cid 0x0004
[00:00:46.601,959] <dbg> bt_att: bt_att_status: chan 0x20021d24 status 0x20021d34

Data at 0: 0xe2
Data at 1: 0xff
Data at 2: 0x0b
Data at 3: 0x44
Data at 4: 0x32
Data at 5: 0x01
Data at 6: 0x00
Data at 7: 0x00
Data at 8: 0xc4
END 
[00:00:46.934,020] <inf> BLE: OnConnectCb: Connected (0): D2:78:94:F0:B8:6C (random)
[00:00:46.959,716] <inf> BLE: Scan: Scanning successfully started
[00:00:46.981,231] <inf> BLE: mtu_exchange: MTU (0): 23
[00:00:47.001,861] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:00:47.023,376] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:00:47.046,203] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:00:47.068,054] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 3
[00:00:47.091,278] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x02
[00:00:47.114,440] <dbg> bt_att: chan_send: code 0x02
Exchange pending...[00:00:47.535,552] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:00:47.557,891] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:00:47.579,040] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:00:48.535,095] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x03 len 2
[00:00:48.558,837] <dbg> bt_att: att_mtu_rsp: Server MTU 23
[00:00:48.579,833] <dbg> bt_att: att_mtu_rsp: Negotiated MTU 23
[00:00:48.601,165] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 2: 1700
[00:00:48.624,481] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:00:48.645,904] <inf> BLE: OnMtuExchangeCb: MTU exchange 0 successful (23)
[00:00:52.535,980] <inf> BLE: le_param_req: LE conn param req: D2:78:94:F0:B8:6C (random) int (0x0020, 0x00a0) lat 4 to 500
[00:00:59.535,125] <inf> BLE: le_param_updated: LE conn param updated: D2:78:94:F0:B8:6C (random) int 0x00a0 lat 4 to 500
[00:01:01.535,064] <dbg> bt_att: bt_att_encrypt_change: chan 0x20021d24 conn 0x20009fe0 handle 0 sec_level 0x02 status 0x00
[00:01:01.561,889] <inf> BLE: OnSecurityChangeCb: Security changed: D2:78:94:F0:B8:6C (random) level 2
[00:01:01.586,608] <dbg> BLE_APP_DISCOVERY: GattDiscover: Start Discovery
[00:01:01.609,252] <err> BLE: StopScan: Scan failed to stop.
[00:01:01.630,554] <dbg> BLE_APP_DISCOVERY: GattDiscover: You provided a sensor
[00:01:01.653,259] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:01.674,804] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:01.697,662] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:01.719,512] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:01.742,736] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x10
[00:01:01.765,930] <dbg> bt_att: chan_send: code 0x10
[00:01:01.935,089] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:01.957,397] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:01.978,546] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:02.135,833] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x11 len 19
[00:01:02.159,637] <dbg> bt_att: att_handle_read_group_rsp: 
[00:01:02.180,725] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 19: 0601000700001808000b0001180c0018000a18
[00:01:02.207,092] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:02.228,485] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 1
[00:01:02.250,518] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 0, handle: 1
[00:01:02.273,193] <dbg> bt_gatt_dm: discovery_process_service: Service detected, handles range: <2, 7>
[00:01:02.298,034] <dbg> bt_gatt_dm: discovery_process_service: Starting descriptors discovery
[00:01:02.322,082] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:02.343,597] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:02.366,424] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:02.388,305] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 5
[00:01:02.411,499] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x04
[00:01:02.434,692] <dbg> bt_att: chan_send: code 0x04
[00:01:02.535,064] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:02.557,403] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:02.578,552] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:02.736,785] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x05 len 21
[00:01:02.760,620] <dbg> bt_att: att_handle_find_info_rsp: 
[00:01:02.781,585] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 21: 01020003280300002a040003280500012a06000328
[00:01:02.808,319] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:02.829,742] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 2
[00:01:02.851,806] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 1, handle: 2
[00:01:02.874,450] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 3
[00:01:02.896,484] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 2, handle: 3
[00:01:02.919,128] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 4
[00:01:02.941,162] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 3, handle: 4
[00:01:02.963,806] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 5
[00:01:02.985,839] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 4, handle: 5
[00:01:03.008,483] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 6
[00:01:03.030,517] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 5, handle: 6
[00:01:03.053,161] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:03.074,676] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:03.097,534] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:03.119,384] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 5
[00:01:03.142,578] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x04
[00:01:03.165,771] <dbg> bt_att: chan_send: code 0x04
[00:01:03.186,584] <inf> BLE: OnPairingCompleteCb: Pairing confirmed to D2:78:94:F0:B8:6C (random), bonded: 1
[00:01:03.935,516] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:03.957,855] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:03.979,003] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:04.135,101] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x05 len 5
[00:01:04.158,843] <dbg> bt_att: att_handle_find_info_rsp: 
[00:01:04.179,809] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 5: 010700042a
[00:01:04.203,613] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:04.225,036] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 7
[00:01:04.247,039] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 6, handle: 7
[00:01:04.269,683] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:01:04.291,687] <dbg> bt_gatt_dm: discovery_process_attribute: Starting characteristic discovery
[00:01:04.316,131] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:04.337,646] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:04.360,504] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:04.382,324] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:04.405,548] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x08
[00:01:04.428,710] <dbg> bt_att: chan_send: code 0x08
[00:01:05.335,083] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:05.357,421] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:05.378,570] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:05.535,308] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x09 len 22
[00:01:05.559,143] <dbg> bt_att: att_handle_read_type_rsp: 
[00:01:05.580,108] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 22: 0702000a0300002a0400020500012a0600020700042a
[00:01:05.606,994] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:05.628,387] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 2
[00:01:05.650,421] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 4
[00:01:05.672,454] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 6
[00:01:05.694,458] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:05.716,003] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:05.738,861] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:05.760,711] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:05.783,935] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x08
[00:01:05.807,159] <dbg> bt_att: chan_send: code 0x08
[00:01:06.735,076] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:06.757,415] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:06.778,564] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:06.935,089] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x01 len 4
[00:01:06.958,862] <dbg> bt_att: att_error_rsp: request 0x08 handle 0x0007 error 0x0a
[00:01:06.982,116] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x0a len 0: 
[00:01:07.005,065] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:07.026,489] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:01:07.048,492] <dbg> bt_gatt_dm: discovery_complete: Discovery complete.
[00:01:07.070,922] <inf> BLE_APP_DISCOVERY: OnDiscoveryCompletedCb: The discovery procedure succeeded
ATT[1]: 	UUID: 0x2803	Handle: 0x0002	Value:
	Characteristic: 0x2a00	Properties: 0x000A
ATT[2]: 	UUID: 0x2a00	Handle: 0x0003	Value:
ATT[3]: 	UUID: 0x2803	Handle: 0x0004	Value:
	Characteristic: 0x2a01	Properties: 0x0002
ATT[4]: 	UUID: 0x2a01	Handle: 0x0005	Value:
ATT[5]: 	UUID: 0x2803	Handle: 0x0006	Value:
	Characteristic: 0x2a04	Properties: 0x0002
ATT[6]: 	UUID: 0x2a04	Handle: 0x0007	Value:
[00:01:07.264,556] <dbg> bt_gatt_dm: svc_attr_memory_release: Attr memory release
[00:01:07.287,414] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:07.308,959] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:07.331,787] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:07.353,637] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:07.376,831] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x10
[00:01:07.400,024] <dbg> bt_att: chan_send: code 0x10
[00:01:08.135,559] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:08.157,867] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:08.179,016] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:08.335,235] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x11 len 19
[00:01:08.359,039] <dbg> bt_att: att_handle_read_group_rsp: 
[00:01:08.380,096] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 19: 0608000b0001180c0018000a18190023001818
[00:01:08.406,433] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:08.427,856] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 8
[00:01:08.449,859] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 0, handle: 8
[00:01:08.472,534] <dbg> bt_gatt_dm: discovery_process_service: Service detected, handles range: <9, 11>
[00:01:08.497,467] <dbg> bt_gatt_dm: discovery_process_service: Starting descriptors discovery
[00:01:08.521,484] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:08.542,999] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:08.565,856] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:08.587,707] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 5
[00:01:08.610,900] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x04
[00:01:08.634,094] <dbg> bt_att: chan_send: code 0x04
[00:01:09.535,064] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:09.557,403] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:09.578,552] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:09.735,168] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x05 len 13
[00:01:09.759,002] <dbg> bt_att: att_handle_find_info_rsp: 
[00:01:09.779,968] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 13: 01090003280a00052a0b000229
[00:01:09.805,267] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:09.826,690] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 9
[00:01:09.848,724] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 1, handle: 9
[00:01:09.871,398] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 10
[00:01:09.893,524] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 2, handle: 10
[00:01:09.916,290] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 11
[00:01:09.938,415] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 3, handle: 11
[00:01:09.961,151] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:01:09.983,154] <dbg> bt_gatt_dm: discovery_process_attribute: Starting characteristic discovery
[00:01:10.007,598] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:10.029,083] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:10.051,940] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:10.073,791] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:10.096,984] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x08
[00:01:10.120,178] <dbg> bt_att: chan_send: code 0x08
[00:01:10.935,546] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:10.957,885] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:10.979,064] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:11.135,131] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x09 len 8
[00:01:11.158,874] <dbg> bt_att: att_handle_read_type_rsp: 
[00:01:11.179,870] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 8: 070900200a00052a
[00:01:11.204,223] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:11.225,677] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 9
[00:01:11.247,741] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:11.269,287] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:11.292,144] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:11.314,025] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:11.337,219] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x08
[00:01:11.360,443] <dbg> bt_att: chan_send: code 0x08
[00:01:12.335,083] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:12.357,421] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:12.378,570] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:12.535,095] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x01 len 4
[00:01:12.558,837] <dbg> bt_att: att_error_rsp: request 0x08 handle 0x000a error 0x0a
[00:01:12.582,122] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x0a len 0: 
[00:01:12.605,072] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:12.626,495] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:01:12.648,529] <dbg> bt_gatt_dm: discovery_complete: Discovery complete.
[00:01:12.670,959] <inf> BLE_APP_DISCOVERY: OnDiscoveryCompletedCb: The discovery procedure succeeded
ATT[1]: 	UUID: 0x2803	Handle: 0x0009	Value:
	Characteristic: 0x2a05	Properties: 0x0020
ATT[2]: 	UUID: 0x2a05	Handle: 0x000A	Value:
ATT[3]: 	UUID: 0x2902	Handle: 0x000B	Value:
	CCCD
[00:01:12.786,163] <dbg> bt_gatt_dm: svc_attr_memory_release: Attr memory release
[00:01:12.809,082] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:12.830,596] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:12.853,454] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:12.875,305] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:12.898,529] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x10
[00:01:12.921,722] <dbg> bt_att: chan_send: code 0x10
[00:01:13.735,534] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:13.757,873] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:13.779,052] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:13.935,180] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x11 len 13
[00:01:13.959,014] <dbg> bt_att: att_handle_read_group_rsp: 
[00:01:13.980,072] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 13: 060c0018000a18190023001818
[00:01:14.005,371] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:14.026,794] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 12
[00:01:14.048,919] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 0, handle: 12
[00:01:14.071,685] <dbg> bt_gatt_dm: discovery_process_service: Service detected, handles range: <13, 24>
[00:01:14.096,710] <dbg> bt_gatt_dm: discovery_process_service: Starting descriptors discovery
[00:01:14.120,727] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:14.142,242] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:14.165,100] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:14.186,950] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 5
[00:01:14.210,174] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x04
[00:01:14.233,367] <dbg> bt_att: chan_send: code 0x04
[00:01:15.135,070] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:15.157,409] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:15.178,588] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:15.535,247] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x05 len 21
[00:01:15.559,051] <dbg> bt_att: att_handle_find_info_rsp: 
[00:01:15.580,047] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 21: 010d0003280e00292a0f0003281000242a11000328
[00:01:15.606,781] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:15.628,204] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 13
[00:01:15.650,329] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 1, handle: 13
[00:01:15.673,065] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 14
[00:01:15.695,190] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 2, handle: 14
[00:01:15.717,926] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 15
[00:01:15.740,051] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 3, handle: 15
[00:01:15.762,786] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 16
[00:01:15.784,912] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 4, handle: 16
[00:01:15.807,678] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 17
[00:01:15.829,803] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 5, handle: 17
[00:01:15.852,539] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:15.874,084] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:15.896,911] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:15.918,792] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 5
[00:01:15.941,986] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x04
[00:01:15.965,179] <dbg> bt_att: chan_send: code 0x04
[00:01:16.735,534] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:16.757,843] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:16.778,991] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:16.935,241] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x05 len 21
[00:01:16.959,045] <dbg> bt_att: att_handle_find_info_rsp: 
[00:01:16.980,010] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 21: 011200272a130003281400262a150003281600282a
[00:01:17.006,713] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:17.028,106] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 18
[00:01:17.050,201] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 6, handle: 18
[00:01:17.072,937] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 19
[00:01:17.095,031] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 7, handle: 19
[00:01:17.117,736] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 20
[00:01:17.139,831] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 8, handle: 20
[00:01:17.162,536] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 21
[00:01:17.184,631] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 9, handle: 21
[00:01:17.207,366] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 22
[00:01:17.229,461] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 10, handle: 22
[00:01:17.252,288] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:17.273,803] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:17.296,630] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:17.318,450] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 5
[00:01:17.341,644] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x04
[00:01:17.364,837] <dbg> bt_att: chan_send: code 0x04
[00:01:18.135,528] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:18.157,836] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:18.178,985] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:18.535,156] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x05 len 9
[00:01:18.558,868] <dbg> bt_att: att_handle_find_info_rsp: 
[00:01:18.579,833] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 9: 01170003281800502a
[00:01:18.604,339] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:18.625,762] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 23
[00:01:18.647,857] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 11, handle: 23
[00:01:18.670,684] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 24
[00:01:18.692,779] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 12, handle: 24
[00:01:18.715,606] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:01:18.737,609] <dbg> bt_gatt_dm: discovery_process_attribute: Starting characteristic discovery
[00:01:18.762,054] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:18.783,569] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:18.806,396] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:18.828,247] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:18.851,470] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x08
[00:01:18.874,664] <dbg> bt_att: chan_send: code 0x08
[00:01:19.735,076] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:19.757,415] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:19.778,564] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:19.935,241] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x09 len 22
[00:01:19.959,075] <dbg> bt_att: att_handle_read_type_rsp: 
[00:01:19.980,041] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 22: 070d00020e00292a0f00021000242a1100021200272a
[00:01:20.006,927] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:20.028,350] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 13
[00:01:20.050,445] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 15
[00:01:20.072,570] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 17
[00:01:20.094,696] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:20.116,210] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:20.139,068] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:20.160,919] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:20.184,112] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x08
[00:01:20.207,305] <dbg> bt_att: chan_send: code 0x08
[00:01:21.135,101] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:21.157,440] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:21.178,588] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:21.535,308] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x09 len 22
[00:01:21.559,143] <dbg> bt_att: att_handle_read_type_rsp: 
[00:01:21.580,139] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 22: 071300021400262a1500021600282a1700021800502a
[00:01:21.607,055] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:21.628,479] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 19
[00:01:21.650,604] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 21
[00:01:21.672,729] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 23
[00:01:21.694,854] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:21.716,400] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:21.739,227] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:21.761,077] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:21.784,271] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x08
[00:01:21.807,464] <dbg> bt_att: chan_send: code 0x08
[00:01:22.735,076] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:22.757,415] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:22.778,594] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:22.935,089] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x01 len 4
[00:01:22.958,831] <dbg> bt_att: att_error_rsp: request 0x08 handle 0x0018 error 0x0a
[00:01:22.982,147] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x0a len 0: 
[00:01:23.005,065] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:23.026,489] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:01:23.048,522] <dbg> bt_gatt_dm: discovery_complete: Discovery complete.
[00:01:23.070,953] <inf> BLE_APP_DISCOVERY: OnDiscoveryCompletedCb: The discovery procedure succeeded
ATT[1]: 	UUID: 0x2803	Handle: 0x000D	Value:
	Characteristic: 0x2a29	Properties: 0x0002
ATT[2]: 	UUID: 0x2a29	Handle: 0x000E	Value:
ATT[3]: 	UUID: 0x2803	Handle: 0x000F	Value:
	Characteristic: 0x2a24	Properties: 0x0002
ATT[4]: 	UUID: 0x2a24	Handle: 0x0010	Value:
ATT[5]: 	UUID: 0x2803	Handle: 0x0011	Value:
	Characteristic: 0x2a27	Properties: 0x0002
ATT[6]: 	UUID: 0x2a27	Handle: 0x0012	Value:
ATT[7]: 	UUID: 0x2803	Handle: 0x0013	Value:
	Characteristic: 0x2a26	Properties: 0x0002
ATT[8]: 	UUID: 0x2a26	Handle: 0x0014	Value:
ATT[9]: 	UUID: 0x2803	Handle: 0x0015	Value:
	Characteristic: 0x2a28	Properties: 0x0002
ATT[10]: 	UUID: 0x2a28	Handle: 0x0016	Value:
ATT[11]: 	UUID: 0x2803	Handle: 0x0017	Value:
	Characteristic: 0x2a50	Properties: 0x0002
ATT[12]: 	UUID: 0x2a50	Handle: 0x0018	Value:
[00:01:23.433,929] <dbg> bt_gatt_dm: svc_attr_memory_release: Attr memory release
[00:01:23.456,817] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:23.478,363] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:23.501,190] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:23.523,010] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:23.546,234] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x10
[00:01:23.569,427] <dbg> bt_att: chan_send: code 0x10
[00:01:24.135,528] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:24.157,867] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:24.179,046] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:24.335,113] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x11 len 7
[00:01:24.358,856] <dbg> bt_att: att_handle_read_group_rsp: 
[00:01:24.379,943] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 7: 06190023001818
[00:01:24.404,113] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:24.425,537] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 25
[00:01:24.447,662] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 0, handle: 25
[00:01:24.470,458] <dbg> bt_gatt_dm: discovery_process_service: Service detected, handles range: <26, 35>
[00:01:24.495,483] <dbg> bt_gatt_dm: discovery_process_service: Starting descriptors discovery
[00:01:24.519,531] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:24.541,076] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:24.563,903] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:24.585,784] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 5
[00:01:24.608,978] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x04
[00:01:24.632,202] <dbg> bt_att: chan_send: code 0x04
[00:01:25.535,064] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:25.557,403] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:25.578,552] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:25.735,229] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x05 len 21
[00:01:25.759,063] <dbg> bt_att: att_handle_find_info_rsp: 
[00:01:25.780,059] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 21: 011a0003281b00632a1c0002291d0003281e00652a
[00:01:25.806,793] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:25.828,247] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 26
[00:01:25.850,372] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 1, handle: 26
[00:01:25.873,138] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 27
[00:01:25.895,263] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 2, handle: 27
[00:01:25.917,999] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 28
[00:01:25.940,093] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 3, handle: 28
[00:01:25.962,829] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 29
[00:01:25.984,924] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 4, handle: 29
[00:01:26.007,659] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 30
[00:01:26.029,754] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 5, handle: 30
[00:01:26.052,490] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:26.074,005] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:26.096,832] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:26.118,682] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 5
[00:01:26.141,876] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x04
[00:01:26.165,069] <dbg> bt_att: chan_send: code 0x04
[00:01:26.935,516] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:26.957,855] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:26.979,003] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:27.135,253] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x05 len 21
[00:01:27.159,057] <dbg> bt_att: att_handle_find_info_rsp: 
[00:01:27.180,053] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 21: 011f00032820005d2a210003282200662a23000229
[00:01:27.206,756] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:27.228,179] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 31
[00:01:27.250,305] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 6, handle: 31
[00:01:27.273,071] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 32
[00:01:27.295,166] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 7, handle: 32
[00:01:27.317,932] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 33
[00:01:27.340,026] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 8, handle: 33
[00:01:27.362,792] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 34
[00:01:27.384,887] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 9, handle: 34
[00:01:27.407,653] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 35
[00:01:27.429,748] <dbg> bt_gatt_dm: attr_store: Attr store, pos: 10, handle: 35
[00:01:27.452,575] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:01:27.474,609] <dbg> bt_gatt_dm: discovery_process_attribute: Starting characteristic discovery
[00:01:27.499,084] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:27.520,629] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:27.543,487] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:27.565,368] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:27.588,592] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x08
[00:01:27.611,785] <dbg> bt_att: chan_send: code 0x08
[00:01:28.335,540] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:28.357,879] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:28.379,058] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:28.535,308] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x09 len 22
[00:01:28.559,143] <dbg> bt_att: att_handle_read_type_rsp: 
[00:01:28.580,139] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 22: 071a00101b00632a1d00021e00652a1f000220005d2a
[00:01:28.607,025] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:28.628,448] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 26
[00:01:28.650,573] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 29
[00:01:28.672,698] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 31
[00:01:28.694,824] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:28.716,369] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:28.739,196] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:28.761,047] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:28.784,240] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x08
[00:01:28.807,434] <dbg> bt_att: chan_send: code 0x08
[00:01:29.735,076] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:29.757,385] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:29.778,533] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:29.935,119] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x09 len 8
[00:01:29.958,862] <dbg> bt_att: att_handle_read_type_rsp: 
[00:01:29.979,827] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x00 len 8: 072100282200662a
[00:01:30.004,150] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:30.025,543] <dbg> bt_gatt_dm: discovery_callback: Attr: handle 33
[00:01:30.047,607] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:30.069,122] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:30.091,918] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:30.113,739] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 7
[00:01:30.136,932] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x08
[00:01:30.160,064] <dbg> bt_att: chan_send: code 0x08
[00:01:31.135,101] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:31.157,379] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:31.178,497] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:01:31.335,083] <dbg> bt_att: bt_att_recv: Received ATT chan 0x20021d20 code 0x01 len 4
[00:01:31.358,825] <dbg> bt_att: att_error_rsp: request 0x08 handle 0x0022 error 0x0a
[00:01:31.382,080] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x0a len 0: 
[00:01:31.404,998] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:01:31.426,391] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:01:31.448,394] <dbg> bt_gatt_dm: discovery_complete: Discovery complete.
[00:01:31.470,794] <inf> BLE_APP_DISCOVERY: OnDiscoveryCompletedCb: The discovery procedure succeeded
ATT[1]: 	UUID: 0x2803	Handle: 0x001A	Value:
	Characteristic: 0x2a63	Properties: 0x0010
ATT[2]: 	UUID: 0x2a63	Handle: 0x001B	Value:
ATT[3]: 	UUID: 0x2902	Handle: 0x001C	Value:
	CCCD
ATT[4]: 	UUID: 0x2803	Handle: 0x001D	Value:
	Characteristic: 0x2a65	Properties: 0x0002
ATT[5]: 	UUID: 0x2a65	Handle: 0x001E	Value:
ATT[6]: 	UUID: 0x2803	Handle: 0x001F	Value:
	Characteristic: 0x2a5d	Properties: 0x0002
ATT[7]: 	UUID: 0x2a5d	Handle: 0x0020	Value:
ATT[8]: 	UUID: 0x2803	Handle: 0x0021	Value:
	Characteristic: 0x2a66	Properties: 0x0028
ATT[9]: 	UUID: 0x2a66	Handle: 0x0022	Value:
ATT[10]: 	UUID: 0x2902	Handle: 0x0023	Value:
	CCCD
[00:01:31.789,001] <dbg> CPWR: bt_cpwr_handles_assign: Getting handles from CPWR service.
[00:01:31.812,561] <dbg> CPWR: bt_cpwr_handles_assign: Found handle for CPWR Measurement characteristic.
[00:01:31.837,432] <dbg> CPWR: bt_cpwr_handles_assign: Found handle for CCC of CPWR Measurement CCC characteristic.
[00:01:31.863,281] <dbg> CPWR: bt_cpwr_subscribe_receive: Subscribe: val: 27, ccc: 28
[00:01:31.886,657] <dbg> bt_att: bt_att_req_alloc: req 0x20027b54
[00:01:31.908,172] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b54
[00:01:31.930,969] <dbg> bt_att: bt_att_chan_req_send: req 0x20027b54
[00:01:31.952,789] <dbg> bt_att: chan_req_send: chan 0x20021d20 req 0x20027b54 len 5
[00:01:31.975,982] <dbg> bt_att: bt_att_chan_send: chan 0x20021d20 flags 4 code 0x12
[00:01:31.999,145] <dbg> bt_att: chan_send: code 0x12
[00:01:32.019,561] <dbg> CPWR: bt_cpwr_subscribe_receive: [SUBSCRIBED]
[00:01:32.041,473] <dbg> bt_gatt_dm: svc_attr_memory_release: Attr memory release
[00:01:32.064,331] <dbg> bt_att: bt_att_req_alloc: req 0x20027b38
[00:01:32.085,815] <dbg> bt_att: bt_att_req_send: conn 0x20009fe0 req 0x20027b38
[00:01:32.535,522] <dbg> bt_att: att_sent: conn 0x20009fe0 chan 0x20021d24
[00:01:32.557,861] <dbg> bt_att: bt_att_sent: chan 0x20021d20
[00:01:32.579,010] <dbg> bt_att: chan_req_sent: chan 0x20021d20 chan->req 0x20027b54
[00:02:02.602,233] <err> bt_att: att_timeout: ATT Timeout
[00:02:02.623,260] <dbg> bt_att: bt_att_disconnected: chan 0x20021d24 cid 0x0004
[00:02:02.646,087] <dbg> bt_att: att_chan_detach: chan 0x20021d20
[00:02:02.667,602] <dbg> bt_att: att_handle_rsp: chan 0x20021d20 err 0x0e len 0: 
[00:02:02.690,521] <dbg> bt_att: bt_att_req_free: req 0x20027b54
[00:02:02.711,944] <inf> CPWR: notify_process: Notifications disabled.
[00:02:02.733,856] <inf> APP_CPWR_CLIENT: unsubscribed_cpwr_measurement_cb: Unsubcribed ! 
[00:02:02.757,507] <dbg> bt_gatt_dm: discovery_callback: NULL attribute
[00:02:02.779,541] <dbg> bt_gatt_dm: discovery_complete_not_found: Discover complete. No service found.
[00:02:02.804,321] <dbg> bt_gatt_dm: svc_attr_memory_release: Attr memory release
[00:02:02.827,209] <inf> BLE_APP_DISCOVERY: OnDiscoveryServiceNotFoundCb: The service discovery procedure has ended or no services could found during the discover
[00:02:02.858,062] <inf> BLE: Scan: Scanning successfully started
[00:02:02.879,547] <dbg> bt_att: bt_att_req_free: req 0x20027b38

The procedure is as follow :

• Scanning for device.
• Connect.
• Change security if possible.
• Stop scan.
• Start discovery.
• If we discover a wanted service we subscribe to it.
• Continue the discovery.
• If no more services to discover end the discovery.

Do you have an idea why it doesn't work with this device in particular ? 

Thanks in advance.

Martin.

[Hung Bui]

Hi Mart, 

I would suggest to capture a sniffer trace to see what happens over the air. I'm not so sure on which exact ATT request that got timed out.  There is a tutorial on how to use the sniffer trace in this course: https://academy.nordicsemi.com/courses/bluetooth-low-energy-fundamentals/lessons/lesson-6-bluetooth-le-sniffer/

Do you have control over the peripheral's side ? Was there any assert ? 

[Martdur]

Hi Hung,

Thanks for your response.

I have no control over the peripheral, maybe you have a sample code that simulate a cycling power sensor.

No there no assert on my side just this error.

I realized that I have thoses two line in the log that I don't with other sensor : 

[00:00:17.566,650] <inf> BLE: le_param_req: LE conn param req: D2:78:94:F0:B8:6C (random) int (0x0020, 0x00a0) lat 4 to 500
[00:00:24.565,734] <inf> BLE: le_param_updated: LE conn param updated: D2:78:94:F0:B8:6C (random) int 0x00a0 lat 4 to 500

I'll try do use the sniffer and come back to you.

[Martdur]

I didn't managed to get further than the chapter 1.2 of Installing the nrf sniffer capture tools.

Can you provide me more information on which folder I need to lauchn the : pip c"py -3 -m pip install -r requirements.txt" command please.

[Hung Bui]

Hi Mart, 

Python folder should be added to the System Environment Variables in Windows. In that you need to add the python folder in to the Path so that it can be called in any folder: 

[Martdur]

Thanks but I get this, I think I launch the command from the wrong directory, am I right ?

C:\Users\dev>cd C:\Program Files\Wireshark\extcap

C:\Program Files\Wireshark\extcap>py -3 -m pip install -r requirements.txt.
Requirement already satisfied: colorama==0.4.6 in c:\users\dev\appdata\local\programs\python\python311\lib\site-packages (from -r requirements.txt. (line 1)) (0.4.6)
Requirement already satisfied: docopt==0.6.2 in c:\users\dev\appdata\local\programs\python\python311\lib\site-packages (from -r requirements.txt. (line 2)) (0.6.2)
Requirement already satisfied: packaging==23.1 in c:\users\dev\appdata\local\programs\python\python311\lib\site-packages (from -r requirements.txt. (line 3)) (23.1)
Requirement already satisfied: pykwalify==1.7.0 in c:\users\dev\appdata\local\programs\python\python311\lib\site-packages (from -r requirements.txt. (line 4)) (1.7.0)
Requirement already satisfied: python-dateutil==2.8.2 in c:\users\dev\appdata\local\programs\python\python311\lib\site-packages (from -r requirements.txt. (line 5)) (2.8.2)
Requirement already satisfied: PyYAML==6.0 in c:\users\dev\appdata\local\programs\python\python311\lib\site-packages (from -r requirements.txt. (line 6)) (6.0)
Requirement already satisfied: six==1.16.0 in c:\users\dev\appdata\local\programs\python\python311\lib\site-packages (from -r requirements.txt. (line 7)) (1.16.0)
Requirement already satisfied: west==1.0.0 in c:\users\dev\appdata\local\programs\python\python311\lib\site-packages (from -r requirements.txt. (line 8)) (1.0.0)
Requirement already satisfied: setuptools in c:\users\dev\appdata\local\programs\python\python311\lib\site-packages (from west==1.0.0->-r requirements.txt. (line 8)) (65.5.0)

C:\Program Files\Wireshark\extcap>

Edit : And the folder is still empty.

[Martdur]

Hi Hung,

My bad I missed something in the tutorial.

I managed to make the nrf sniffer working but I don't understand the trace I had, it's seems that the connexion is bugged after a while.

In the same time I've realized that the central is detecting this sensor with delay so I increased the scan window, and this seems to solve this issue but I still I have the At timeout error.

The concerned peripheral address : 6c b8 f0 94 78 d2

Here the sniffer trace:

/cfs-file/__key/communityserver-discussions-components-files/4/BlePaquert_5F00_FailedToDiscover.pcapng

/cfs-file/__key/communityserver-discussions-components-files/4/BlePaquert_5F00_FailedToDiscover2.pcapng

[Hung Bui]

Hi Mart, 

You would  need to select the advertiser in the drop down list so that the sniffer would follow the connection instead of just scanning for advertising packets. 
We have a tutorial here for nRF Sniffer. It's to be used with nRF Connect examples, but the principle is the same. 
https://academy.nordicsemi.com/courses/bluetooth-low-energy-fundamentals/lessons/lesson-6-bluetooth-le-sniffer/topic/blefund-lesson-6-exercise-2/

[Martdur]

Hello Hung, 

Sorry for the late response I was busy with another topic.

Thanks for the help, I managed to get proper traces.

The central is scanning and connecting to the concerned device then it performs a discovering and then subscribes to the cpwr measurement characteristic.

Then we have this timeout error.

Here the file:

BleAtTimeout.pcapng

Thanks in advance.

Martin.

[Hung Bui]

Hi Martin, 
What I can see is that the central tried to do a write request to the peripheral but it never got a write response, hence a ATT Timeout. The connection is terminated after 30 seconds because of that: 

So it's correct that the issue is when the central trying to enable notification on the cycling power measurement service. 
We need to look into the peripheral code to see why it doesn't response ? 
Is it a 3rd party sensor ? If it is you may want to capture how it react when you connect with a phone and their native app to control the sensor. 

[Martdur]

Yes it's a third party sensor made by powertap.

It tested it with the nrf connect app on phone and I could subscribe to the characteristic.

I don't know why it work with the phone, I'm investigating.

Here the response and the traces file : 

2100.BleAtTimeout.pcapng

[Hung Bui]

The only major difference I can see is that in the new trace you sent there were no pairing/bonding. When with the nrf53 central, it was pairing with the device. 
Usually it's the opposite that you need to pair to be able to use the device, but I don't know about this device. Maybe you can try to not pair with the peripheral to see if it works.