In Nrf7002dk facing issue with TWT Powersave Mechanism

[Jaswanth Rajigiri]

Hi,

1. I was facing issue with my nordic device saying 

[02:16:46.206,481] <err> net_pkt: Data buffer (1328) allocation failed.
[02:16:46.206,481] <wrn> net_conn: pkt cloning failed, pkt 0x2003a454 dropped

continuously , attached video below for your reference.

 2. I need to test TWT powersave using Nrf7002dk, what i was doing was 

• Initially turning on powersave using " wifi ps on "
• Connecting wifi using " wifi connect SSID Password "
• After successful connection starting ping to the nordic ip which i took from "net iface show" through my laptop which is in the same network and ping is happening.
• Now entering " wifi twt setup 0 0 2 2 0 0 1 0 20000 3000000 " command and output occurred as uart:~$ wifi twt setup 0 0 2 2 0 0 1 0 20000 3000000
  TWT operation TWT setup with dg: 2, flow_id: 2 requested
  TWT response: TWT reject
  TWT Dialog token: 2
  TWT flow ID: 2
  TWT negotiation type: TWT individual negotiation
  TWT responder: true
  TWT implicit: true
  TWT announce: false
  TWT trigger: false
  TWT wake interval: 19968 us
  TWT interval: 3002000 us
  ======================

But even after i have given wake interval it should go to sleep and should wake after the given wake interval time, but i was not happening in that way, it was continuously pinging or reachable without sleep.

3. When i was giving net tcp connect IP Port it was disconnecting with errors

uart:~$ net tcp connect 172.40.9.203 5001

Connecting from 172.40.9.248:0 to 172.40.9.203:5001

TCP connection failed (-116)

[00:13:28.327,423] <err> os: ***** BUS FAULT *****

[00:13:28.327,423] <err> os: Precise data bus error

[00:13:28.327,423] <err> os: BFAR Address: 0xc809

[00:13:28.327,453] <err> os: r0/a1: 0x00000000 r1/a2: 0x00000002 r2/a3: 0x0000c809

[00:13:28.327,453] <err> os: r3/a4: 0x20012e60 r12/ip: 0x200298f4 r14/lr: 0x0004d67f

[00:13:28.327,453] <err> os: xpsr: 0x21000000

[00:13:28.327,453] <err> os: Faulting instruction address (r15/pc): 0x000738a4

[00:13:28.327,484] <err> os: >>> ZEPHYR FATAL ERROR 25: Unknown error on CPU 0

[00:13:28.327,484] <err> os: Current thread: 0x20002af8 (tcp_work)

[00:13:28.390,502] <err> coredump: #CD:BEGIN#

[00:13:28.396,270] <err> coredump: #CD:5a4501000300050019000000

[00:13:28.403,594] <err> coredump: #CD:4102004400

[00:13:28.409,698] <err> coredump: #CD:000000000200000009c80000602e0120f49802207fd60400a438070000000021

[00:13:28.420,501] <err> coredump: #CD:98d4002000000000000000000000000000000000000000000000000000000000

[00:13:28.431,304] <err> coredump: #CD:00000000

[00:13:28.437,225] <err> coredump: #CD:4d0100f82a0020c02b0020

[00:13:28.444,366] <err> coredump: #CD:70230020a47d0020000000000080f20000000000000000000000000000000000

[00:13:28.455,169] <err> coredump: #CD:00000000000000000000000000000000a447002000000000ffffffffffffffff

[00:13:28.466,003] <err> coredump: #CD:00000000ffffffff000000000000000070d4002000000000502b0020502b0020

[00:13:28.476,806] <err> coredump: #CD:000100001dd70400f82a00200000000000000000903500207463705f776f726b

[00:13:28.487,609] <err> coredump: #CD:0000000000000000000000000000000000000000000000000000000020d10020

[00:13:28.498,413] <err> coredump: #CD:0004000000000000301600200000000000000000000000000000000000000000

[00:13:28.509,216] <err> coredump: #CD:0000000000000000

[00:13:28.515,838] <err> coredump: #CD:4d010020d1002020d50020

[00:13:28.522,979] <err> coredump: #CD:f0f0f0f0aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.533,782] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.544,586] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.555,419] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.566,223] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.577,026] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.587,829] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.598,632] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.609,436] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.620,239] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.631,042] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.641,845] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.652,648] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.663,452] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.674,255] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.685,058] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:13:28.695,861] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa7035002045db0400

[00:13:28.706,665] <err> coredump: #CD:e0330020887d0020f82a0020c1db0400fffffffff82a002080120020df320700

[00:13:28.717,498] <err> coredump: #CD:ffffffffffffffffffffffffed88002003000000ed88002000000000cf040600

[00:13:28.728,302] <err> coredump: #CD:70070020f4980220ed880020487e0700b8d30020030000000723060035230600

[00:13:28.739,105] <err> coredump: #CD:03000000d47207001b000000d2880020072306000d2b06000300000000000000

[00:13:28.749,908] <err> coredump: #CD:0000000000000000d0410000b03c0020f87d07000a000000a519090018000000

[00:13:28.760,711] <err> coredump: #CD:1b00000049260600f87d07008926060051260600c0d400201c000000ab5a0000

[00:13:28.771,514] <err> coredump: #CD:3ed400203b3307005029002051260600a6190900000000000000000005160200

[00:13:28.782,318] <err> coredump: #CD:48d40020a319090050010000181a002000000000d61d00007035002045db0400

[00:13:28.793,121] <err> coredump: #CD:e0330020887d0020f82a0020c1db0400fffffffff82a0020a4470020df320700

[00:13:28.803,955] <err> coredump: #CD:ffffffffffffffffffffffff2ddd04000000000000ed00e0887d002000000000

[00:13:28.814,758] <err> coredump: #CD:f4980220f1d40400a8bf000000000061f2ffffffa44700200000000002000000

[00:13:28.825,561] <err> coredump: #CD:09c80000602e0120f49802207fd60400a4380700000000210000000044930220

[00:13:28.836,364] <err> coredump: #CD:8cffffff156b0600449302208cfffffff82a0020d4930220b07d0020a1d70400

[00:13:28.847,167] <err> coredump: #CD:ffffffffffffffff00000000aaaaaaaaaaaaaaaa1dd704000000000000000000

[00:13:28.857,971] <err> coredump: #CD:0000000000000000000000000000000000000000ddfc050000000000aaaaaaaa

[00:13:28.868,743] <err> coredump: #CD:END#

[00:13:28.874,328] <err> fatal_error: Resetting system

I need help on both points, ASAP because it was stopping my testing. 

[Håkon Alseth]

Hi,

 

it seems that your video was not fully uploaded. Could you re-attach it?

  

But even after i have given wake interval it should go to sleep and should wake after the given wake interval time, but i was not happening in that way, it was continuously pinging or reachable without sleep.

TWT was rejected by the access point:

TWT operation TWT setup with dg: 2, flow_id: 2 requested
TWT response: TWT reject

Try to adjust your TWT timing, specifically this one:

TWT wake interval: 19968 us

To for instance 65000.

uart:~$ net tcp connect 172.40.9.203 5001

Connecting from 172.40.9.248:0 to 172.40.9.203:5001

TCP connection failed (-116)

[00:13:28.327,423] <err> os: ***** BUS FAULT *****

[00:13:28.327,423] <err> os: Precise data bus error

[00:13:28.327,423] <err> os: BFAR Address: 0xc809

It looks like you are using wifi/shell.

Could you try adding the configurations from overlay-zperf.conf? This will expand the amount of sockets and overall buffers.

 

Kind regards,

Håkon

[Jaswanth Rajigiri]

Hi Alseth

is there any possible to connect virtually so that i can show you configuration and remaining issues which i was facing while building overlay-zperf.

when i was trying to build facing lot of issues, like device is going into scanning state, sometimes only 2.4GHZ is enabling, interface is getting down etc..

Regards

Jaswanth

[Jaswanth Rajigiri]

when i was trying to connect tcp getting following error

uart:~$ net tcp connect 172.40.9.252 5222

Connecting from 172.40.9.251:0 to 172.40.9.252:5222

TCP connection failed (-116)

[00:25:14.302,947] <err> os: ***** MPU FAULT *****

[00:25:14.302,947] <err> os: Data Access Violation

[00:25:14.302,947] <err> os: MMFAR Address: 0xbddd

[00:25:14.302,978] <err> os: r0/a1: 0x00000000 r1/a2: 0x00000002 r2/a3: 0x0000bddd

[00:25:14.302,978] <err> os: r3/a4: 0x200141b0 r12/ip: 0x20067af4 r14/lr: 0x0004dd7f

[00:25:14.302,978] <err> os: xpsr: 0x21000000

[00:25:14.302,978] <err> os: Faulting instruction address (r15/pc): 0x00073472

[00:25:14.303,009] <err> os: >>> ZEPHYR FATAL ERROR 19: Unknown error on CPU 0

[00:25:14.303,009] <err> os: Current thread: 0x20002ea0 (tcp_work)

[00:25:14.365,631] <err> coredump: #CD:BEGIN#

[00:25:14.371,368] <err> coredump: #CD:5a4501000300050013000000

[00:25:14.378,631] <err> coredump: #CD:4102004400

[00:25:14.384,704] <err> coredump: #CD:0000000002000000ddbd0000b0410120f47a06207fdd04007234070000000021

[00:25:14.395,446] <err> coredump: #CD:e8cf002000000000000000000000000000000000000000000000000000000000

[00:25:14.406,158] <err> coredump: #CD:00000000

[00:25:14.412,048] <err> coredump: #CD:4d0100a02e0020682f0020

[00:25:14.419,158] <err> coredump: #CD:80230020c8650020000000000080f20000000000000000000000000000000000

[00:25:14.429,901] <err> coredump: #CD:00000000000000000000000000000000f429002000000000ffffffffffffffff

[00:25:14.440,643] <err> coredump: #CD:00000000ffffffff0000000000000000c0cf002000000000f82e0020f82e0020

[00:25:14.451,385] <err> coredump: #CD:000100001dde0400a02e00200000000000000000884000207463705f776f726b

[00:25:14.462,097] <err> coredump: #CD:0000000000000000000000000000000000000000000000000000000070cc0020

[00:25:14.472,839] <err> coredump: #CD:00040000000000000c1600200000000000000000000000000000000000000000

[00:25:14.483,581] <err> coredump: #CD:0000000000000000

[00:25:14.490,173] <err> coredump: #CD:4d010070cc002070d00020

[00:25:14.497,253] <err> coredump: #CD:f0f0f0f0aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.507,995] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.518,737] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.529,479] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.540,222] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.550,933] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.561,676] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.572,418] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.583,160] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.593,872] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.604,614] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.615,356] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.626,098] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.636,840] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.647,583] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.658,294] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa

[00:25:14.669,036] <err> coredump: #CD:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa6840002045e20400

[00:25:14.679,779] <err> coredump: #CD:d83e0020ac650020a02e0020c1e20400ffffffffa02e00207c120020ad2e0700

[00:25:14.690,521] <err> coredump: #CD:ffffffffffffffffffffffffa171002006000000a171002000000000230c0600

[00:25:14.701,263] <err> coredump: #CD:5c070020f47a0620a17100202879070008cf0020060000006924060097240600

[00:25:14.712,005] <err> coredump: #CD:060000001c6e07001800000089710020692406000b2c06000600000000000000

[00:25:14.722,717] <err> coredump: #CD:00000000000000002041000054470020d87807000a0000004d14090018000000

[00:25:14.733,459] <err> coredump: #CD:1b000000ab270600d8780700eb270600b327060010d000201c0000009f5a0000

[00:25:14.744,201] <err> coredump: #CD:8ecf0020092f0700f82c0020b32706004e14090000000000000000002d260200

[00:25:14.754,943] <err> coredump: #CD:98cf00204b14090050010000281a0020000000007a1e00006840002045e20400

[00:25:14.765,655] <err> coredump: #CD:d83e0020ac650020a02e0020c1e20400ffffffffa02e0020f4290020ad2e0700

[00:25:14.776,397] <err> coredump: #CD:ffffffffffffffffffffffff2de404000000000000ed00e0ac65002000000000

[00:25:14.787,139] <err> coredump: #CD:f47a0620f1db04007cb5000000000061f2fffffff42900200000000002000000

[00:25:14.797,882] <err> coredump: #CD:ddbd0000b0410120f47a06207fdd040072340700000000210000000044750620

[00:25:14.808,624] <err> coredump: #CD:8cffffff156c0600447506208cffffffa02e0020d4750620d4650020a1de0400

[00:25:14.819,335] <err> coredump: #CD:ffffffffffffffff00000000aaaaaaaaaaaaaaaa1dde04000000000000000000

[00:25:14.830,078] <err> coredump: #CD:00000000000000000000000000000000000000003104060000000000aaaaaaaa

[00:25:14.840,820] <err> coredump: #CD:END#

[00:25:14.846,343] <err> fatal_error: Resetting system

*** Booting nRF Connect SDK v2.5.1 ***

[00:00:00.414,062] <wrn> net_zperf: Unable to set IP

[00:00:00.414,093] <wrn> net_zperf: Unable to set IP

[00:00:00.414,154] <inf> net_zperf: Setting IP address 0.0.0.0

[00:00:00.414,184] <inf> net_zperf: Setting destination IP address 0.0.0.0

Starting nrf7002dk_nrf5340_cpuapp with CPU frequency: 128 MHz

[00:00:00.414,215] <inf> net_config: Initializing network

[00:00:00.414,245] <inf> net_config: Waiting interface 1 (0x20001918) to be up...

[00:00:00.414,245] <inf> net_config: Running dhcpv4 client...

[00:00:00.414,520] <inf> wpa_supp: Successfully initialized wpa_supplicant

[Håkon Alseth]

Hi,

 

You are running an older version of NCS. Have you tested any newer versions and observe the same issue? For instance v2.6.2 / v2.5.3 or v2.7.0?

 

Kind regards,

Håkon

[Jaswanth Rajigiri]

I was trying in updated versions 

[Håkon Alseth]

Hi,

 

Jaswanth Rajigiri said:

I was trying in updated versions 

Did you manage to run the wifi/shell sample successfully with v2.8.0?

 

Kind regards,

Håkon