• State Not Answered
  • Replies 5 replies
  • Subscribers 68 subscribers
  • Views 240 views
  • Users 0 members are here
Attachments (2) Download All
Nordic Case Info
  • Case ID: 331419
Options

nRF7002 WIFI connection reliability on sta example

veratek1

Hello,

I have been testing the WIFI on the nRF7002-DK board and have found that it drops out if not used. The WIFI sta example running on the nRF7002-DK connects to a router and the ping command from a pc to the board works. After 15 minutes the ping command no longer works. No errors print out the debug UART. The led blink thread is still running.

I have found that if the sta example is modified to have a socket server and sends something to a client every 5 minutes it stays active overnight. Also the line:
CONFIG_NRF_WIFI_LOW_POWER=n
made it last longer but it still lost connection after some hours if not used. I have also tried setting timeout to SYS_FOREVER_MS but it still dropped out.

I am using SDK version v2.6.0.

Parents Reply Children
  • 0 in reply to Marte Myrvold

    Hello,

    Switching to version 2.6.1 or 2.7.0 did not improve WIFI reliability. I did not have the antenna attached to the board before but adding it did not change the boards reliability. Do you think it could be that my router is somehow breaking the connection? It would be helpful if someone could try to reproduce this error.

    Thanks.

  • 0 in reply to veratek1

    Hi,

    Can you try setting CONFIG_WPA_SUPP_LOG_LEVEL_DBG=y in prj.conf to get debug logs from the WPA supplicant?

    Can you share your project so I can try reproducing it on my side? Please also include instructions for how to reproduce it. If you do not want to share your code in a public ticket, let me know and I will make it private.

    Best regards,
    Marte

  • 0 in reply to Marte Myrvold

    Hello,

    I have my modified sta example here I found it in "C:\ncs\v2.6.1\nrf\samples\wifi\sta". The only change is the timeout set to SYS_FOREVER_MS and the SSID and CONFIG_STA_KEY_MGMT_NONE for my router.

    To run set the SSID for your router in the prj.conf. Build and flash nrf7002-dk board. The UART prints out:

    Pinging from the pc:

    After 15 minutes:

    No change on serial:

    Here is the example and the log for CONFIG_WPA_SUPP_LOG_LEVEL_DBG:

    8764.sta.zip

    Fullscreen CONFIG_WPA_SUPP_LOG_LEVEL_DBG.txt Download 
    *** Booting nRF Connect SDK v3.5.99-ncs1-1 ***
[00:00:00.637,115] <inf> net_config: Initializing network
[00:00:00.642,974] <inf> net_config: Waiting interface 1 (0x20001470) to be up...
[00:00:00.650,939] <inf> net_config: IPv4 address: 192.168.1.99
[00:00:00.657,226] <inf> net_config: Running dhcpv4 client...
[00:00:00.663,604] <inf> sta: Starting nrf7002dk_nrf5340_cpuapp with CPU frequency: 64 MHz
[00:00:00.672,363] <dbg> wpa_supp: wpa_printf_impl: wpa_supplicant v2.11-devel
[00:00:00.680,145] <inf> wpa_supp: Successfully initialized wpa_supplicant
[00:00:00.687,774] <dbg> wpa_supp: wpa_printf_impl: Adding interface nordic_wlan0

[00:00:00.695,770] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: interface_add, argc: 5

[00:00:00.704,559] <dbg> wpa_supp: wpa_printf_impl: argv[0]: interface_add

[00:00:00.711,914] <dbg> wpa_supp: wpa_printf_impl: argv[1]: nordic_wlan0

[00:00:00.719,177] <dbg> wpa_supp: wpa_printf_impl: argv[2]: zephyr

[00:00:00.725,952] <dbg> wpa_supp: wpa_printf_impl: argv[3]: zephyr

[00:00:00.732,696] <dbg> wpa_supp: wpa_printf_impl: argv[4]: zephyr

[00:00:00.739,746] <dbg> wpa_supp: wpa_printf_impl: RX global ctrl_iface - hexdump_ascii(len=85):
[00:00:00.748,962] <dbg> wpa_supp: _wpa_hexdump_ascii:
                                   49 4e 54 45 52 46 41 43  45 5f 41 44 44 20 6e 6f |INTERFAC E_ADD no
                                   72 64 69 63 5f 77 6c 61  6e 30 09 7a 65 70 68 79 |rdic_wla n0.zephy
                                   72 09 7a 65 70 68 79 72  09 7a 65 70 68 79 72 09 |r.zephyr .zephyr.
                                   09 09 09 20 6e 6f 72 64  69 63 5f 77 6c 61 6e 30 |... nord ic_wlan0
                                   20 7a 65 70 68 79 72 20  7a 65 70 68 79 72 20 7a | zephyr  zephyr z
                                   65 70 68 79 72                                   |ephyr
[00:00:00.808,624] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE GLOBAL INTERFACE_ADD 'nordic_wlan0       zephyr  zephyr  zephyr                           nordic_wlan0 zephyr zephyr zephyr'
[00:00:00.823,150] <dbg> wpa_supp: wpa_printf_impl: Initializing interface 'nordic_wlan0' conf 'zephyr' driver 'zephyr' ctrl_interface 'zephyr' bridge 'N/A'
[00:00:00.848,175] <dbg> wpa_supp: wpa_printf_impl: Add interface nordic_wlan0 to a new radio N/A
[00:00:00.861,511] <dbg> wpa_supp: wpa_printf_impl: wpa_supp: Added 802.11b mode based on 802.11g information
[00:00:00.872,009] <dbg> wpa_supp: wpa_printf_impl: l2_packet_init: iface nordic_wlan0 ifindex 1
[00:00:00.881,286] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Own MAC address: f4:ce:36:00:20:50
[00:00:00.890,869] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: RSN: flushing PMKID list in the driver
[00:00:00.900,787] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: State: DISCONNECTED -> INACTIVE
[00:00:00.910,217] <dbg> wpa_supp: wpa_printf_impl: MBO: Update non-preferred channels, non_pref_chan=N/A
[00:00:00.920,318] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Added interface nordic_wlan0
[00:00:00.929,351] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: State: INACTIVE -> DISCONNECTED
[00:00:01.672,271] <inf> sta: QSPI Encryption disabled
[00:00:01.677,764] <inf> sta: Static IP address (overridable): 192.168.1.99/255.255.255.0 -> 192.168.1.1
[00:00:01.687,683] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: remove_network, argc: 2

[00:00:01.696,563] <dbg> wpa_supp: wpa_printf_impl: argv[0]: remove_network

[00:00:01.704,010] <dbg> wpa_supp: wpa_printf_impl: argv[1]: all

[00:00:01.710,815] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Control interface command 'REMOVE_NETWORK all'
[00:00:01.721,405] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: REMOVE_NETWORK all
[00:00:01.729,797] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Control interface command 'ADD_NETWORK'
[00:00:01.739,776] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: ADD_NETWORK
[00:00:01.747,528] <dbg> wpa_supp: wpa_printf_impl: Received len: 260, msg_len:26 - CTRL-EVENT-NETWORK-ADDED 0->END

[00:00:01.758,728] <dbg> wpa_supp: wpa_printf_impl: NET added: 0

[00:00:01.765,228] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: set_network, argc: 4

[00:00:01.773,864] <dbg> wpa_supp: wpa_printf_impl: argv[0]: set_network

[00:00:01.781,036] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0

[00:00:01.787,353] <dbg> wpa_supp: wpa_printf_impl: argv[2]: ssid

[00:00:01.793,945] <dbg> wpa_supp: wpa_printf_impl: argv[3]: "SEA_DRAGON"

[00:00:01.801,544] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Control interface command 'SET_NETWORK [REMOVED]'
[00:00:01.812,408] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SET_NETWORK id=0 name='ssid'
[00:00:01.821,228] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: value - hexdump_ascii(len=12): [REMOVED]
[00:00:01.831,115] <dbg> wpa_supp: wpa_printf_impl: ssid - hexdump_ascii(len=10):
[00:00:01.838,958] <dbg> wpa_supp: _wpa_hexdump_ascii:
                                   53 45 41 5f 44 52 41 47  4f 4e                   |SEA_DRAG ON
[00:00:01.853,851] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: set_network, argc: 4

[00:00:01.862,457] <dbg> wpa_supp: wpa_printf_impl: argv[0]: set_network

[00:00:01.869,659] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0

[00:00:01.875,976] <dbg> wpa_supp: wpa_printf_impl: argv[2]: scan_ssid

[00:00:01.882,965] <dbg> wpa_supp: wpa_printf_impl: argv[3]: 1

[00:00:01.889,617] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Control interface command 'SET_NETWORK [REMOVED]'
[00:00:01.900,512] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SET_NETWORK id=0 name='scan_ssid'
[00:00:01.909,759] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: value - hexdump_ascii(len=1): [REMOVED]
[00:00:01.919,525] <dbg> wpa_supp: wpa_printf_impl: scan_ssid=1 (0x1)
[00:00:01.926,574] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: set_network, argc: 4

[00:00:01.935,211] <dbg> wpa_supp: wpa_printf_impl: argv[0]: set_network

[00:00:01.942,382] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0

[00:00:01.948,699] <dbg> wpa_supp: wpa_printf_impl: argv[2]: key_mgmt

[00:00:01.955,627] <dbg> wpa_supp: wpa_printf_impl: argv[3]: NONE

[00:00:01.962,524] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Control interface command 'SET_NETWORK [REMOVED]'
[00:00:01.973,419] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SET_NETWORK id=0 name='key_mgmt'
[00:00:01.982,574] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: value - hexdump_ascii(len=4): [REMOVED]
[00:00:01.992,401] <dbg> wpa_supp: wpa_printf_impl: key_mgmt: 0x4
[00:00:01.999,084] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: set_network, argc: 4

[00:00:02.007,720] <dbg> wpa_supp: wpa_printf_impl: argv[0]: set_network

[00:00:02.014,892] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0

[00:00:02.021,209] <dbg> wpa_supp: wpa_printf_impl: argv[2]: ieee80211w

[00:00:02.028,320] <dbg> wpa_supp: wpa_printf_impl: argv[3]: 0

[00:00:02.034,973] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Control interface command 'SET_NETWORK [REMOVED]'
[00:00:02.045,837] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SET_NETWORK id=0 name='ieee80211w'
[00:00:02.055,175] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: value - hexdump_ascii(len=1): [REMOVED]
[00:00:02.064,971] <dbg> wpa_supp: wpa_printf_impl: ieee80211w=0 (0x0)
[00:00:02.072,113] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: enable_network, argc: 2

[00:00:02.080,993] <dbg> wpa_supp: wpa_printf_impl: argv[0]: enable_network

[00:00:02.088,439] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0

[00:00:02.095,092] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Control interface command 'ENABLE_NETWORK 0'
[00:00:02.105,499] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: ENABLE_NETWORK id=0
[00:00:02.113,616] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Setting scan request: 0.000000 sec
[00:00:02.123,413] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: State: DISCONNECTED -> SCANNING
[00:00:02.132,720] <dbg> wpa_supp: wpa_printf_impl: Scan SSID - hexdump_ascii(len=10):
[00:00:02.140,960] <dbg> wpa_supp: _wpa_hexdump_ascii:
                                   53 45 41 5f 44 52 41 47  4f 4e                   |SEA_DRAG ON
[00:00:02.155,670] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Include wildcard SSID in the scan request
[00:00:02.166,015] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Add radio work 'scan'@0x200099d0
[00:00:02.175,445] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: First radio work item in the queue - schedule start immediately
[00:00:02.187,774] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Starting radio work 'scan'@0x200099d0 after 0.012299 second wait
[00:00:02.200,378] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: select_network, argc: 2

[00:00:02.209,289] <dbg> wpa_supp: wpa_printf_impl: argv[0]: select_network

[00:00:02.216,705] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0

[00:00:02.226,135] <dbg> wpa_supp: wpa_printf_impl: wpa_drv_zep_scan2: Scan requested - scan timeout 35 seconds

[00:00:02.239,562] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Control interface command 'SELECT_NETWORK 0'
[00:00:02.251,586] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SELECT_NETWORK id=0
[00:00:02.260,864] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Setting scan request: 0.000000 sec
[00:00:02.271,270] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Already scanning - Reschedule the incoming scan req
[00:00:02.282,348] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Setting scan request: 1.000000 sec
[00:00:02.292,358] <inf> sta: Connection requested
[00:00:02.297,546] <inf> sta: ==================
[00:00:02.302,520] <inf> sta: State: SCANNING
[00:00:02.307,403] <dbg> wpa_supp: wpa_printf_impl: Passing message 47 to wpa_supplicant
[00:00:02.315,948] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Event SCAN_STARTED (47) received
[00:00:02.325,408] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Own scan request started a scan in 0.085998 seconds
[00:00:02.336,730] <dbg> wpa_supp: wpa_printf_impl: Received len: 260, msg_len:24 - CTRL-EVENT-SCAN-STARTED ->END

[00:00:02.607,421] <inf> sta: ==================
[00:00:02.613,128] <inf> sta: State: SCANNING
[00:00:02.917,999] <inf> sta: ==================
[00:00:02.922,973] <inf> sta: State: SCANNING
[00:00:03.227,844] <inf> sta: ==================
[00:00:03.232,788] <inf> sta: State: SCANNING
[00:00:03.292,114] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Already scanning - Reschedule the incoming scan req
[00:00:03.303,161] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Setting scan request: 1.000000 sec
[00:00:03.537,689] <inf> sta: ==================
[00:00:03.542,633] <inf> sta: State: SCANNING
[00:00:03.847,534] <inf> sta: ==================
[00:00:03.852,508] <inf> sta: State: SCANNING
[00:00:04.157,409] <inf> sta: ==================
[00:00:04.162,384] <inf> sta: State: SCANNING
[00:00:04.312,927] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Already scanning - Reschedule the incoming scan req
[00:00:04.323,974] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Setting scan request: 1.000000 sec
[00:00:04.467,254] <inf> sta: ==================
[00:00:04.472,198] <inf> sta: State: SCANNING
[00:00:04.777,099] <inf> sta: ==================
[00:00:04.782,043] <inf> sta: State: SCANNING
[00:00:05.086,944] <inf> sta: ==================
[00:00:05.091,888] <inf> sta: State: SCANNING
[00:00:05.333,770] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Already scanning - Reschedule the incoming scan req
[00:00:05.344,818] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Setting scan request: 1.000000 sec
[00:00:05.396,789] <inf> sta: ==================
[00:00:05.401,733] <inf> sta: State: SCANNING
[00:00:05.706,634] <inf> sta: ==================
[00:00:05.711,578] <inf> sta: State: SCANNING
[00:00:06.016,479] <inf> sta: ==================
[00:00:06.021,423] <inf> sta: State: SCANNING
[00:00:06.123,321] <dbg> wpa_supp: wpa_printf_impl: Passing message 3 to wpa_supplicant
[00:00:06.131,774] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Event SCAN_RESULTS (3) received
[00:00:06.141,174] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Scan completed in 4.-184234 seconds
[00:00:06.153,869] <dbg> wpa_supp: wpa_printf_impl: Sorted scan results
[00:00:06.160,949] <dbg> wpa_supp: wpa_printf_impl: b0:39:56:56:9b:28 freq=2412 qual=0 noise=-89~ level=-18 snr=71* flags=0xb age=3847 est=65000
[00:00:06.176,300] <dbg> wpa_supp: wpa_printf_impl: IEs - hexdump(len=172): 00 0a 53 45 41 5f 44 52 41 47 4f 4e 01 08 82 84 8b 96 12 24 48 6c 03 01 01 2a 01 04 32 04 0c 18 30 60 2d 1a ec 11 17 ff ff 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 3d 16 01 00 00 00 00 00 00 00 00 00 00 00 00
[00:00:06.203,765] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: BSS: Start scan result update 1
[00:00:06.213,256] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: BSS: Add new id 0 BSSID b0:39:56:56:9b:28 SSID 'SEA_DRAGON' freq 2412
[00:00:06.226,013] <dbg> wpa_supp: wpa_printf_impl: BSS: last_scan_res_used=1/32
[00:00:06.233,856] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: New scan results available (own=1 ext=0)
[00:00:06.244,140] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Radio work 'scan'@0x200099d0 done in 4.056366 seconds
[00:00:06.255,401] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: radio_work_free('scan'@0x200099d0): num_active_works --> 0
[00:00:06.267,089] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Scan results matching the currently selected network
[00:00:06.278,442] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: 0: b0:39:56:56:9b:28 freq=2412 level=-18 snr=71 est_throughput=65000
[00:00:06.290,985] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Selecting BSS from priority group 0
[00:00:06.300,842] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: 0: b0:39:56:56:9b:28 ssid='SEA_DRAGON' wpa_ie_len=0 rsn_ie_len=0 caps=0x401 level=-18 freq=2412
[00:00:06.315,826] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0:    allow in non-WPA/WPA2
[00:00:06.324,676] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0:    selected BSS b0:39:56:56:9b:28 ssid='SEA_DRAGON'
[00:00:06.336,059] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Considering connect request: reassociate: 1  selected: b0:39:56:56:9b:28  bssid: 00:00:00:00:00:00  pending: 00:00:00:00:00:00  wpa_state: SCANNING  ssid=0x200080b0  current_ssid=0x200080b0
[00:00:06.359,130] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Request association with b0:39:56:56:9b:28
[00:00:06.369,415] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: No ongoing scan/p2p-scan found to abort
[00:00:06.379,455] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Add radio work 'sme-connect'@0x20008338
[00:00:06.389,495] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: First radio work item in the queue - schedule start immediately
[00:00:06.401,794] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Starting AP scan for wildcard SSID
[00:00:06.411,529] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Add radio work 'scan'@0x20009a80
[00:00:06.420,989] <dbg> wpa_supp: wpa_printf_impl: Received len: 260, msg_len:40 - CTRL-EVENT-BSS-ADDED 0 b0:39:56:56:9b:28->END

[00:00:06.433,319] <dbg> wpa_supp: wpa_printf_impl: Received len: 260, msg_len:24 - CTRL-EVENT-SCAN-RESULTS ->END

[00:00:06.444,122] <dbg> wpa_supp: wpa_printf_impl: Event not supported: CTRL-EVENT-SCAN-RESULTS

[00:00:06.453,735] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Starting radio work 'sme-connect'@0x20008338 after 0.064209 second wait
[00:00:06.466,522] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: WPA: clearing own WPA/RSN IE
[00:00:06.475,555] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: RSN: clearing own RSNXE
[00:00:06.484,191] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Automatic auth_alg selection: 0x1
[00:00:06.493,682] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: WPA: clearing AP WPA IE
[00:00:06.502,288] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: WPA: clearing AP RSN IE
[00:00:06.510,894] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: WPA: clearing AP RSNXE
[00:00:06.519,439] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: WPA: clearing own WPA/RSN IE
[00:00:06.528,472] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: RSN: clearing own RSNXE
[00:00:06.537,078] <dbg> wpa_supp: wpa_printf_impl: RRM: Determining whether RRM can be used - device support: 0x50
[00:00:06.547,851] <dbg> wpa_supp: wpa_printf_impl: RRM: No RRM in network
[00:00:06.556,060] <dbg> wpa_supp: wpa_printf_impl: Added supported operating classes IE - hexdump(len=10): 3b 08 51 51 52 73 76 79 7c 7d
[00:00:06.568,878] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Cancelling scan request
[00:00:06.577,606] <inf> wpa_supp: nordic_wlan0: SME: Trying to authenticate with b0:39:56:56:9b:28 (SSID='SEA_DRAGON' freq=2412 MHz)
[00:00:06.590,087] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: State: SCANNING -> AUTHENTICATING
[00:00:06.600,097] <inf> sta: ==================
[00:00:06.605,072] <inf> sta: State: AUTHENTICATING
[00:00:06.615,142] <dbg> wpa_supp: wpa_printf_impl: Received len: 260, msg_len:84 - SME: Trying to authenticate with b0:39:56:56:9b:28 (SSID='SEA_DRAGON' freq=2412 MHz)->END

[00:00:06.865,478] <dbg> wpa_supp: wpa_printf_impl: Passing message 10 to wpa_supplicant
[00:00:06.874,023] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Event AUTH (10) received
[00:00:06.882,843] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: SME: Authentication response: peer=b0:39:56:56:9b:28 auth_type=0 auth_transaction=2 status_code=0
[00:00:06.897,827] <dbg> wpa_supp: wpa_printf_impl: SME: Authentication response IEs - hexdump(len=0): [NULL]
[00:00:06.908,416] <dbg> wpa_supp: wpa_printf_impl: SME: Association Request IEs - hexdump(len=23): 7f 0b 04 00 4a 02 00 40 40 40 00 20 20 3b 08 51 51 52 73 76 79 7c 7d
[00:00:06.923,980] <inf> wpa_supp: nordic_wlan0: Trying to associate with b0:39:56:56:9b:28 (SSID='SEA_DRAGON' freq=2412 MHz)
[00:00:06.935,760] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: State: AUTHENTICATING -> ASSOCIATING
[00:00:06.945,800] <inf> sta: ==================
[00:00:06.950,805] <inf> sta: State: AUTHENTICATING
[00:00:06.961,212] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: WPA: clearing own WPA/RSN IE
[00:00:06.970,397] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: RSN: clearing own RSNXE
[00:00:06.986,236] <dbg> wpa_supp: wpa_printf_impl: Received len: 260, msg_len:76 - Trying to associate with b0:39:56:56:9b:28 (SSID='SEA_DRAGON' freq=2412 MHz)->END

[00:00:07.007,843] <dbg> wpa_supp: wpa_printf_impl: Event: -805240830
[00:00:07.015,899] <dbg> wpa_supp: wpa_printf_impl: Passing message 0 to wpa_supplicant
[00:00:07.024,444] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Event ASSOC (0) received
[00:00:07.033,172] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Association info event
[00:00:07.042,785] <dbg> wpa_supp: wpa_printf_impl: req_ies - hexdump(len=100): 00 0a 53 45 41 5f 44 52 41 47 4f 4e 01 08 02 04 0b 16 0c 12 18 24 32 04 30 48 60 6c 21 02 00 1e 5a 03 24 01 00 c7 01 00 2d 1a 0c 88 1e ff 00 00 00 00 00 00 00 00 00 00 00 01 00 00 00 00 00 00 00 00 00 00 7f 0b 04 00 4a 02 00 40
[00:00:07.072,326] <dbg> wpa_supp: wpa_printf_impl: resp_ies - hexdump(len=189): 01 08 82 84 8b 96 12 24 48 6c 32 04 0c 18 30 60 dd 18 00 50 f2 02 01 01 00 00 03 a4 00 00 27 a4 00 00 42 43 5e 00 62 32 2f 00 2d 1a ee 11 17 ff ff 00 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 3d 16 01 00 06 0
[00:00:07.099,761] <dbg> wpa_supp: wpa_printf_impl: Unknown Broadcom information element ignored (type=52 len=26)
[00:00:07.110,382] <dbg> wpa_supp: wpa_printf_impl: IEEE 802.11 element parse ignored unknown element (id=74 elen=14)
[00:00:07.121,398] <dbg> wpa_supp: wpa_printf_impl: unknown vendor specific information element ignored (vendor OUI 00:0c:43 len=7)
[00:00:07.133,605] <dbg> wpa_supp: wpa_printf_impl: Unknown Broadcom information element ignored (type=52 len=26)
[00:00:07.144,256] <dbg> wpa_supp: wpa_printf_impl: IEEE 802.11 element parse ignored unknown element (id=74 elen=14)
[00:00:07.155,273] <dbg> wpa_supp: wpa_printf_impl: unknown vendor specific information element ignored (vendor OUI 00:0c:43 len=7)
[00:00:07.167,480] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: freq=2412 MHz
[00:00:07.175,231] <dbg> wpa_supp: wpa_printf_impl: Unknown Broadcom information element ignored (type=52 len=26)
[00:00:07.185,882] <dbg> wpa_supp: wpa_printf_impl: unknown vendor specific information element ignored (vendor OUI 00:0c:43 len=7)
[00:00:07.198,120] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: WPA: clearing own WPA/RSN IE
[00:00:07.207,153] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: RSN: clearing own RSNXE
[00:00:07.215,789] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: State: ASSOCIATING -> ASSOCIATED
[00:00:07.233,276] <inf> net_config: IPv6 address: fe80::f6ce:36ff:fe00:2050
[00:00:07.241,363] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Associated to a new BSS: BSSID=b0:39:56:56:9b:28
[00:00:07.252,471] <dbg> wpa_supp: wpa_printf_impl: wpa_drv_zep_get_ssid: SSID size: 10

[00:00:07.261,077] <inf> wpa_supp: nordic_wlan0: Associated with b0:39:56:56:9b:28
[00:00:07.269,104] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: WPA: Association event - clear replay counter
[00:00:07.279,632] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: WPA: Clear old PTK
[00:00:07.287,811] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Cancelling authentication timeout
[00:00:07.297,302] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: State: ASSOCIATED -> COMPLETED
[00:00:07.306,610] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Radio work 'sme-connect'@0x20008338 done in 1.-147125 seconds
[00:00:07.318,572] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: radio_work_free('sme-connect'@0x20008338): num_active_works --> 0
[00:00:07.330,963] <inf> wpa_supp: nordic_wlan0: CTRL-EVENT-CONNECTED - Connection to b0:39:56:56:9b:28 completed [id=0 id_str=]
[00:00:07.347,229] <inf> sta: Connected
[00:00:07.351,837] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Cancelling scan request
[00:00:07.360,595] <inf> wpa_supp: nordic_wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
[00:00:07.369,537] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Starting radio work 'scan'@0x20009a80 after 1.-51483 second wait
[00:00:07.385,406] <dbg> wpa_supp: wpa_printf_impl: wpa_drv_zep_scan2: Scan requested - scan timeout 35 seconds

[00:00:07.396,209] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Control interface command 'SIGNAL_POLL'
[00:00:07.422,180] <dbg> wpa_supp: wpa_printf_impl: Received len: 260, msg_len:33 - Associated with b0:39:56:56:9b:28->END

[00:00:07.433,776] <dbg> wpa_supp: wpa_printf_impl: Received len: 260, msg_len:79 - CTRL-EVENT-CONNECTED - Connection to b0:39:56:56:9b:28 completed [id=0 id_str=]->END

[00:00:07.449,493] <dbg> wpa_supp: wpa_printf_impl: Received len: 260, msg_len:40 - CTRL-EVENT-SUBNET-STATUS-UPDATE status=0->END

[00:00:07.461,669] <dbg> wpa_supp: wpa_printf_impl: Event not supported: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0

[00:00:07.472,564] <dbg> wpa_supp: wpa_printf_impl: Passing message 47 to wpa_supplicant
[00:00:07.481,109] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Event SCAN_STARTED (47) received
[00:00:07.490,539] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Own scan request started a scan in 0.094391 seconds
[00:00:07.501,861] <dbg> wpa_supp: wpa_printf_impl: Received len: 260, msg_len:24 - CTRL-EVENT-SCAN-STARTED ->END

[00:00:07.524,078] <inf> sta: ==================
[00:00:07.529,113] <inf> sta: State: ASSOCIATED
[00:00:07.534,027] <inf> sta: Interface Mode: STATION
[00:00:07.539,489] <inf> sta: Link Mode: WIFI 4 (802.11n/HT)
[00:00:07.545,532] <inf> sta: SSID: SEA_DRAGON
[00:00:07.550,384] <inf> sta: BSSID: B0:39:56:56:9B:28
[00:00:07.556,243] <inf> sta: Band: 2.4GHz
[00:00:07.560,729] <inf> sta: Channel: 1
[00:00:07.565,185] <inf> sta: Security: OPEN
[00:00:07.569,854] <inf> sta: MFP: Disable
[00:00:07.574,371] <inf> sta: RSSI: -19
[00:00:07.879,058] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Control interface command 'SIGNAL_POLL'
[00:00:07.893,798] <inf> sta: ==================
[00:00:07.898,773] <inf> sta: State: COMPLETED
[00:00:07.903,717] <inf> sta: Interface Mode: STATION
[00:00:07.909,149] <inf> sta: Link Mode: WIFI 4 (802.11n/HT)
[00:00:07.915,649] <inf> sta: SSID: SEA_DRAGON
[00:00:07.921,173] <inf> sta: BSSID: B0:39:56:56:9B:28
[00:00:07.927,062] <inf> sta: Band: 2.4GHz
[00:00:07.931,976] <inf> sta: Channel: 1
[00:00:07.936,798] <inf> sta: Security: OPEN
[00:00:07.941,467] <inf> sta: MFP: Disable
[00:00:07.945,983] <inf> sta: RSSI: -72
[00:00:08.109,008] <inf> net_dhcpv4: Received: 192.168.1.100
[00:00:08.115,203] <inf> net_config: IPv4 address: 192.168.1.100
[00:00:08.121,551] <inf> net_config: Lease time: 86400 seconds
[00:00:08.127,807] <inf> net_config: Subnet: 255.255.255.0
[00:00:08.133,697] <inf> net_config: Router: 192.168.1.1
[00:00:08.139,465] <inf> sta: DHCP IP address: 192.168.1.100
[00:00:10.200,134] <dbg> wpa_supp: wpa_printf_impl: Passing message 3 to wpa_supplicant
[00:00:10.209,014] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Event SCAN_RESULTS (3) received
[00:00:10.218,444] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Scan completed in 3.-272125 seconds
[00:00:10.231,719] <dbg> wpa_supp: wpa_printf_impl: Sorted scan results
[00:00:10.238,800] <dbg> wpa_supp: wpa_printf_impl: b0:39:56:56:9b:28 freq=2412 qual=0 noise=-89~ level=-18 snr=71* flags=0xb age=2770 est=65000
[00:00:10.254,364] <dbg> wpa_supp: wpa_printf_impl: IEs - hexdump(len=188): 00 0a 53 45 41 5f 44 52 41 47 4f 4e 01 08 82 84 8b 96 12 24 48 6c 03 01 01 32 04 0c 18 30 60 07 06 55 53 20 01 0b 14 33 08 20 01 02 03 04 05 06 07 33 08 21 05 06 07 08 09 0a 0b 05 04 00 01 00 00 2a 01 04 2d 1a ec 11 17 ff ff 00 00
[00:00:10.283,874] <dbg> wpa_supp: wpa_printf_impl: Beacon IEs - hexdump(len=188): 00 0a 53 45 41 5f 44 52 41 47 4f 4e 01 08 82 84 8b 96 12 24 48 6c 03 01 01 32 04 0c 18 30 60 07 06 55 53 20 01 0b 14 33 08 20 01 02 03 04 05 06 07 33 08 21 05 06 07 08 09 0a 0b 05 04 00 01 00 00 2a 01 04 2d 1a ec 11 17 ff ff
[00:00:10.311,340] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: BSS: Start scan result update 2
[00:00:10.320,770] <dbg> wpa_supp: wpa_printf_impl: BSS: last_scan_res_used=1/32
[00:00:10.328,613] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: New scan results available (own=1 ext=0)
[00:00:10.338,897] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Radio work 'scan'@0x20009a80 done in 3.-30609 seconds
[00:00:10.350,158] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: radio_work_free('scan'@0x20009a80): num_active_works --> 0
[00:00:10.361,846] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Scan results matching the currently selected network
[00:00:10.373,229] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: 0: b0:39:56:56:9b:28 freq=2412 level=-18 snr=71 est_throughput=65000
[00:00:10.385,742] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: Selecting BSS from priority group 0
[00:00:10.395,629] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0: 0: b0:39:56:56:9b:28 ssid='SEA_DRAGON' wpa_ie_len=0 rsn_ie_len=0 caps=0x401 level=-18 freq=2412
[00:00:10.410,583] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0:    allow in non-WPA/WPA2
[00:00:10.419,433] <dbg> wpa_supp: wpa_printf_impl: nordic_wlan0:    selected current BSS b0:39:56:56:9b:28 ssid='SEA_DRAGON'
[00:00:10.431,365] <dbg> wpa_supp: wpa_printf_impl: Received len: 260, msg_len:24 - CTRL-EVENT-SCAN-RESULTS ->END

[00:00:10.442,138] <dbg> wpa_supp: wpa_printf_impl: Event not supported: CTRL-EVENT-SCAN-RESULTS

  • 0 in reply to veratek1

    Hi,

    Are you able to reproduce the issue when connecting to other networks?

    I was not able to reproduce the issue on my side.

    Best regards,
    Marte

Related