MQTT sample problem

I' m using custom board of nrf5340 with nrf7002, and the SDK version is ncs v2.5.2.

I've tried to build and run, but the log in RTT viewer is only print the line "Bringing network interface up and connecting to the network", It seems the program is stuck, and it didn't proceed to execute.

  

and I wondered it is because of the MAC address, so I added the "set_mac _address" function into "network.c" and executed it in the function "network_task", but it printed other errors below.

prj.conf

#prj.conf
#
# Copyright (c) 2023 Nordic Semiconductor ASA
#
# SPDX-License-Identifier: LicenseRef-Nordic-5-Clause
#

# Common configurations for all boards

CONFIG_WIFI=y
CONFIG_WIFI_NRF700X=y
CONFIG_NET_L2_WIFI_MGMT=y
CONFIG_HEAP_MEM_POOL_SIZE=25000

# System settings
CONFIG_NEWLIB_LIBC=y

# Networking
CONFIG_NETWORKING=y
CONFIG_NET_L2_ETHERNET=y
CONFIG_NET_NATIVE=y
CONFIG_NET_OFFLOAD=y

CONFIG_INIT_STACKS=y

# Memories
CONFIG_MAIN_STACK_SIZE=4096

# General
CONFIG_HW_ID_LIBRARY=y
CONFIG_ASSERT=y
CONFIG_WIFI_CREDENTIALS_STATIC_SSID="allen"
CONFIG_WIFI_CREDENTIALS_STATIC_PASSWORD="123" 
CONFIG_WIFI_CREDENTIALS_STATIC_TYPE_OPEN=y

CONFIG_MQTT_SAMPLE_TRANSPORT_BROKER_HOSTNAME="test.mosquitto.org"
CONFIG_MQTT_SAMPLE_TRANSPORT_PUBLISH_TOPIC="my/publish/topic"
CONFIG_MQTT_SAMPLE_TRANSPORT_SUBSCRIBE_TOPIC="my/subscribe/topic"

CONFIG_MQTT_SAMPLE_LED=n
CONFIG_DK_LIBRARY=n

# CONFIG_MQTT_SAMPLE_TRIGGER_THREAD_STACK_SIZE=2048
# Logging
CONFIG_LOG=y
# CONFIG_NET_LOG=y
# CONFIG_MQTT_LOG_LEVEL_DBG=y
# Networking
CONFIG_NETWORKING=y
CONFIG_NET_SOCKETS_OFFLOAD=y
CONFIG_NET_SOCKETS=y
CONFIG_NET_SOCKETS_POSIX_NAMES=y
CONFIG_NET_IPV4=y
CONFIG_NET_TCP=y
CONFIG_NET_NATIVE=y
CONFIG_NET_CONNECTION_MANAGER=y

# ZBus
CONFIG_ZBUS=y

# Zephyr state framework
CONFIG_SMF=y

# MQTT
CONFIG_MQTT_HELPER=y
CONFIG_MQTT_CLEAN_SESSION=y

#config RTT
CONFIG_LOG=y
CONFIG_LOG_PRINTK=y
# CONFIG_LOG_MODE_DEFERRED=y
CONFIG_SERIAL=n
CONFIG_UART_CONSOLE=n
CONFIG_USE_SEGGER_RTT=y
CONFIG_CONSOLE=y
CONFIG_RTT_CONSOLE=y
CONFIG_LOG_BACKEND_RTT=y
CONFIG_LOG_BACKEND_UART=n
CONFIG_SEGGER_RTT_BUFFER_SIZE_UP=4096
CONFIG_LOG_BUFFER_SIZE=4096
# Disable internal DCDC
CONFIG_BOARD_ENABLE_DCDC_APP=n
CONFIG_BOARD_ENABLE_DCDC_NET=n
CONFIG_BOARD_ENABLE_DCDC_HV=n

# Use internal capacitor for 32M xtal and set capacitor value to 8.5pF 
# (ranging from 7.0 pF to 20.0 pF in 0.5 pF steps
# can be enabled on pins XC1 and XC2. This option specifies doubled
# capacitance value for the two capacitors. Set it to 14 to get 7.0 pF
# for each capacitor, 15 to get 7.5 pF, and so on.
# )
CONFIG_SOC_HFXO_CAP_INTERNAL=y
CONFIG_SOC_HFXO_CAP_INT_VALUE_X2=17
 
#  Use internal capacitor for 32k xtal and set capacitor value to 7pF 
# (only 4 options of internal capacitor value settings for 32k xtal
#   -INT_6PF
#   -INT_7PF
#   -INT_9PF
#   - USE external capacitor)
CONFIG_SOC_ENABLE_LFXO=y
CONFIG_SOC_LFXO_CAP_INT_7PF=y

Below is the function what I added. And this function is executed normally in the Scan sample.

int8_t wifi_set_mac_address()
{
	struct net_if *iface = net_if_get_default();
	uint8_t mac_addr[6] = {0xF0, 0xCE, 0x36, 0x00, 0x00, 0x4A};

	struct ethernet_req_params eth_param;
	memcpy(eth_param.mac_address.addr, mac_addr, 6);

	// if (net_if_down(iface))
	// {
	// 	printk("<Err> Couldn't shutdown WiFi interface\n");
	// 	return -1;
	// }
	printk("<Inf> Setting MAC address\n");
	if (net_mgmt(NET_REQUEST_ETHERNET_SET_MAC_ADDRESS, iface, &eth_param, sizeof(struct ethernet_req_params)))
	{
		printk("<Err> Couldn't set new MAC Address\n");
		return -2;
	}

	int ret = memcmp(net_if_get_link_addr(iface)->addr, mac_addr, sizeof(mac_addr));
	if (ret)
	{
		printk("<Err> Couldn't change MAC address\n");
	}

	if (net_if_up(iface))
	{
		printk("<Err> Couldn't wake up interface\n");
		return -3;
	}

	return 0;
}

How can I run the MQTT sample correctly?

Parents
  • Hi,

    Can you provide a link to the MQTT sample which you used?

    Best regards,
    Dejan

  • Hi Allen,

    For testing purposes, you could try to use  CONFIG_WIFI_FIXED_MAC_ADDRESS Kconfig option.

    Best regards,
    Dejan

  • Hi,

    For testing purposes, you could try to use  CONFIG_WIFI_FIXED_MAC_ADDRESS Kconfig option.

    After setting "CONFIG_WIFI_FIXED_MAC_ADDRESS", the application works fine.

    But if I want to use for formal purposes, how can I set mac address correctly?

    Custom Board log message

    00> [00:00:00.468,872] <inf> fs_nvs: 6 Sectors of 4096 bytes
    00> [00:00:00.469,177] <inf> fs_nvs: alloc wra: 0, fe8
    00> [00:00:00.469,482] <inf> fs_nvs: data wra: 0, 0
    00> *** Booting nRF Connect SDK v2.5.2 ***
    00> [00:00:00.470,214] <dbg> wpa_supp: wpa_printf_impl: wpa_supplicant v2.11-devel
    00> [00:00:00.471,710] <inf> wpa_supp: Successfully initialized wpa_supplicant
    00> [00:00:00.473,358] <inf> network: Bringing network interface up and connecting to the network
    00> [00:00:00.473,815] <dbg> mqtt_helper: mqtt_state_set: State transition: MQTT_STATE_UNINIT --> MQTT_STATE_DISCONNECTED
    00> [00:00:00.474,517] <dbg> wpa_supp: wpa_printf_impl: Adding interface wlan0
    00> 
    00> [00:00:00.475,036] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: interface_add, argc: 5
    00> 
    00> [00:00:00.475,494] <dbg> wpa_supp: wpa_printf_impl: argv[0]: interface_add
    00> 
    00> [00:00:00.475,891] <dbg> wpa_supp: wpa_printf_impl: argv[1]: wlan0
    00> 
    00> [00:00:00.476,287] <dbg> wpa_supp: wpa_printf_impl: argv[2]: zephyr
    00> 
    00> [00:00:00.476,684] <dbg> wpa_supp: wpa_printf_impl: argv[3]: zephyr
    00> 
    00> [00:00:00.477,081] <dbg> wpa_supp: wpa_printf_impl: argv[4]: zephyr
    00> 
    00> [00:00:00.478,057] <dbg> wpa_supp: wpa_printf_impl: RX global ctrl_iface - hexdump_ascii(len=44):
    00> [00:00:00.478,515] <dbg> wpa_supp: _wpa_hexdump_ascii: 
    00>                                    49 4e 54 45 52 46 41 43  45 5f 41 44 44 20 77 6c |INTERFAC E_ADD wl
    00>                                    61 6e 30 09 7a 65 70 68  79 72 09 7a 65 70 68 79 |an0.zeph yr.zephy
    00>                                    72 09 7a 65 70 68 79 72  09 09 09 09             |r.zephyr ....    
    00> [00:00:00.480,804] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE GLOBAL INTERFACE_ADD 'wlan0  zephyr  zephyr  zephyr        '
    00> [00:00:00.481,933] <dbg> wpa_supp: wpa_printf_impl: Initializing interface 'wlan0' conf 'zephyr' driver 'zephyr' ctrl_interface 'zephyr' bridge 'N/A'
    00> [00:00:00.483,215] <dbg> mqtt_helper: mqtt_helper_poll_loop: Waiting for connection_poll_sem
    00> [00:00:00.486,907] <dbg> wpa_supp: wpa_printf_impl: Add interface wlan0 to a new radio N/A
    00> [00:00:00.491,882] <dbg> wpa_supp: wpa_printf_impl: wpa_supp: Added 802.11b mode based on 802.11g information
    00> [00:00:00.492,645] <dbg> wpa_supp: wpa_printf_impl: l2_packet_init: iface wlan0 ifindex 1
    00> [00:00:00.493,286] <dbg> wpa_supp: wpa_printf_impl: wlan0: Own MAC address: f0:ce:36:00:00:4a
    00> [00:00:00.493,865] <dbg> wpa_supp: wpa_printf_impl: wlan0: RSN: flushing PMKID list in the driver
    00> [00:00:00.494,598] <dbg> wpa_supp: wpa_printf_impl: wlan0: State: DISCONNECTED -> INACTIVE
    00> [00:00:00.496,185] <dbg> wpa_supp: wpa_printf_impl: MBO: Update non-preferred channels, non_pref_chan=N/A
    00> [00:00:00.496,948] <dbg> wpa_supp: wpa_printf_impl: wlan0: Added interface wlan0
    00> [00:00:00.497,467] <dbg> wpa_supp: wpa_printf_impl: wlan0: State: INACTIVE -> DISCONNECTED
    00> OK
    00> [00:00:00.505,950] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: remove_network, argc: 2
    00> 
    00> [00:00:00.506,408] <dbg> wpa_supp: wpa_printf_impl: argv[0]: remove_network
    00> 
    00> [00:00:00.506,835] <dbg> wpa_supp: wpa_printf_impl: argv[1]: all
    00> 
    00> [00:00:00.508,178] <dbg> wpa_supp: wpa_printf_impl: wlan0: Control interface command 'REMOVE_NETWORK all'
    00> [00:00:00.508,728] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: REMOVE_NETWORK all
    00> OK
    00> [00:00:00.510,314] <dbg> wpa_supp: wpa_printf_impl: wlan0: Control interface command 'ADD_NETWORK'
    00> [00:00:00.510,833] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: ADD_NETWORK
    00> [00:00:00.511,657] <dbg> wpa_supp: wpa_printf_impl: NET added: 0
    00> 
    00> [00:00:00.512,084] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: set_network, argc: 4
    00> 
    00> [00:00:00.512,542] <dbg> wpa_supp: wpa_printf_impl: argv[0]: set_network
    00> 
    00> [00:00:00.512,939] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0
    00> 
    00> [00:00:00.513,336] <dbg> wpa_supp: wpa_printf_impl: argv[2]: ssid
    00> 
    00> [00:00:00.513,732] <dbg> wpa_supp: wpa_printf_impl: argv[3]: "allen"
    00> 
    00> [00:00:00.515,258] <dbg> wpa_supp: wpa_printf_impl: wlan0: Control interface command 'SET_NETWORK [REMOVED]'
    00> [00:00:00.515,838] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SET_NETWORK id=0 name='ssid'
    00> [00:00:00.516,326] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: value - hexdump_ascii(len=7): [REMOVED]
    00> [00:00:00.516,845] <dbg> wpa_supp: wpa_printf_impl: ssid - hexdump_ascii(len=5):
    00> [00:00:00.517,242] <dbg> wpa_supp: _wpa_hexdump_ascii: 
    00>                                    61 6c 6c 65 6e                                   |allen            
    00> OK
    00> [00:00:00.518,280] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: set_network, argc: 4
    00> 
    00> [00:00:00.518,768] <dbg> wpa_supp: wpa_printf_impl: argv[0]: set_network
    00> 
    00> [00:00:00.519,165] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0
    00> 
    00> [00:00:00.519,531] <dbg> wpa_supp: wpa_printf_impl: argv[2]: scan_ssid
    00> 
    00> [00:00:00.519,927] <dbg> wpa_supp: wpa_printf_impl: argv[3]: 1
    00> 
    00> [00:00:00.521,087] <dbg> wpa_supp: wpa_printf_impl: Received event: CTRL-EVENT-NETWORK-ADDED 0
    00> 
    00> [00:00:00.522,338] <dbg> wpa_supp: wpa_printf_impl: wlan0: Control interface command 'SET_NETWORK [REMOVED]'
    00> [00:00:00.522,949] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SET_NETWORK id=0 name='scan_ssid'
    00> [00:00:00.523,437] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: value - hexdump_ascii(len=1): [REMOVED]
    00> [00:00:00.523,925] <dbg> wpa_supp: wpa_printf_impl: scan_ssid=1 (0x1)
    00> OK
    00> [00:00:00.524,597] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: set_network, argc: 4
    00> 
    00> [00:00:00.525,054] <dbg> wpa_supp: wpa_printf_impl: argv[0]: set_network
    00> 
    00> [00:00:00.525,451] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0
    00> 
    00> [00:00:00.525,848] <dbg> wpa_supp: wpa_printf_impl: argv[2]: key_mgmt
    00> 
    00> [00:00:00.526,245] <dbg> wpa_supp: wpa_printf_impl: argv[3]: NONE
    00> 
    00> [00:00:00.527,770] <dbg> wpa_supp: wpa_printf_impl: wlan0: Control interface command 'SET_NETWORK [REMOVED]'
    00> [00:00:00.528,350] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SET_NETWORK id=0 name='key_mgmt'
    00> [00:00:00.528,839] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: value - hexdump_ascii(len=4): [REMOVED]
    00> [00:00:00.529,357] <dbg> wpa_supp: wpa_printf_impl: key_mgmt: 0x4
    00> OK
    00> [00:00:00.529,998] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: set_network, argc: 4
    00> 
    00> [00:00:00.530,456] <dbg> wpa_supp: wpa_printf_impl: argv[0]: set_network
    00> 
    00> [00:00:00.530,853] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0
    00> 
    00> [00:00:00.531,250] <dbg> wpa_supp: wpa_printf_impl: argv[2]: ieee80211w
    00> 
    00> [00:00:00.531,646] <dbg> wpa_supp: wpa_printf_impl: argv[3]: 0
    00> 
    00> [00:00:00.533,172] <dbg> wpa_supp: wpa_printf_impl: wlan0: Control interface command 'SET_NETWORK [REMOVED]'
    00> [00:00:00.533,752] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SET_NETWORK id=0 name='ieee80211w'
    00> [00:00:00.534,240] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: value - hexdump_ascii(len=1): [REMOVED]
    00> [00:00:00.534,759] <dbg> wpa_supp: wpa_printf_impl: ieee80211w=0 (0x0)
    00> OK
    00> [00:00:00.535,461] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: set_network, argc: 4
    00> 
    00> [00:00:00.535,919] <dbg> wpa_supp: wpa_printf_impl: argv[0]: set_network
    00> 
    00> [00:00:00.536,315] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0
    00> 
    00> [00:00:00.536,682] <dbg> wpa_supp: wpa_printf_impl: argv[2]: psk
    00> 
    00> [00:00:00.537,078] <dbg> wpa_supp: wpa_printf_impl: argv[3]: "allen0424"
    00> 
    00> [00:00:00.538,604] <dbg> wpa_supp: wpa_printf_impl: wlan0: Control interface command 'SET_NETWORK [REMOVED]'
    00> [00:00:00.539,215] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SET_NETWORK id=0 name='psk'
    00> [00:00:00.539,703] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: value - hexdump_ascii(len=11): [REMOVED]
    00> [00:00:00.540,222] <dbg> wpa_supp: wpa_printf_impl: PSK (ASCII passphrase) - hexdump_ascii(len=9): [REMOVED]
    00> [00:00:02.431,243] <dbg> wpa_supp: wpa_printf_impl: PSK (from passphrase) - hexdump(len=32): [REMOVED]
    00> OK
    00> [00:00:02.431,976] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: set_network, argc: 4
    00> 
    00> [00:00:02.432,434] <dbg> wpa_supp: wpa_printf_impl: argv[0]: set_network
    00> 
    00> [00:00:02.432,830] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0
    00> 
    00> [00:00:02.433,227] <dbg> wpa_supp: wpa_printf_impl: argv[2]: key_mgmt
    00> 
    00> [00:00:02.433,624] <dbg> wpa_supp: wpa_printf_impl: argv[3]: WPA-PSK
    00> 
    00> [00:00:02.435,150] <dbg> wpa_supp: wpa_printf_impl: wlan0: Control interface command 'SET_NETWORK [REMOVED]'
    00> [00:00:02.435,760] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SET_NETWORK id=0 name='key_mgmt'
    00> [00:00:02.436,248] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: value - hexdump_ascii(len=7): [REMOVED]
    00> [00:00:02.436,737] <dbg> wpa_supp: wpa_printf_impl: key_mgmt: 0x2
    00> OK
    00> [00:00:02.437,408] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: set_network, argc: 4
    00> 
    00> [00:00:02.437,866] <dbg> wpa_supp: wpa_printf_impl: argv[0]: set_network
    00> 
    00> [00:00:02.438,262] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0
    00> 
    00> [00:00:02.438,659] <dbg> wpa_supp: wpa_printf_impl: argv[2]: ieee80211w
    00> 
    00> [00:00:02.439,056] <dbg> wpa_supp: wpa_printf_impl: argv[3]: 1
    00> 
    00> [00:00:02.440,582] <dbg> wpa_supp: wpa_printf_impl: wlan0: Control interface command 'SET_NETWORK [REMOVED]'
    00> [00:00:02.441,162] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SET_NETWORK id=0 name='ieee80211w'
    00> [00:00:02.441,650] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: value - hexdump_ascii(len=1): [REMOVED]
    00> [00:00:02.442,169] <dbg> wpa_supp: wpa_printf_impl: ieee80211w=1 (0x1)
    00> OK
    00> [00:00:02.442,840] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: enable_network, argc: 2
    00> 
    00> [00:00:02.443,298] <dbg> wpa_supp: wpa_printf_impl: argv[0]: enable_network
    00> 
    00> [00:00:02.443,725] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0
    00> 
    00> [00:00:02.445,220] <dbg> wpa_supp: wpa_printf_impl: wlan0: Control interface command 'ENABLE_NETWORK 0'
    00> [00:00:02.445,770] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: ENABLE_NETWORK id=0
    00> [00:00:02.446,319] <dbg> wpa_supp: wpa_printf_impl: wlan0: Setting scan request: 0.000000 sec
    00> OK
    00> [00:00:02.447,082] <dbg> wpa_supp: wpa_printf_impl: Calling wpa_cli: select_network, argc: 2
    00> 
    00> [00:00:02.447,540] <dbg> wpa_supp: wpa_printf_impl: argv[0]: select_network
    00> 
    00> [00:00:02.447,967] <dbg> wpa_supp: wpa_printf_impl: argv[1]: 0
    00> 
    00> [00:00:02.449,279] <dbg> wpa_supp: wpa_printf_impl: wlan0: State: DISCONNECTED -> SCANNING
    00> [00:00:02.449,798] <dbg> wpa_supp: wpa_printf_impl: Scan SSID - hexdump_ascii(len=5):
    00> [00:00:02.450,195] <dbg> wpa_supp: _wpa_hexdump_ascii: 
    00>                                    61 6c 6c 65 6e                                   |allen            
    00> [00:00:02.451,019] <dbg> wpa_supp: wpa_printf_impl: wlan0: Include wildcard SSID in the scan request
    00> [00:00:02.451,812] <dbg> wpa_supp: wpa_printf_impl: wlan0: Add radio work 'scan'@0x20042110
    00> [00:00:02.452,392] <dbg> wpa_supp: wpa_printf_impl: wlan0: First radio work item in the queue - schedule start immediately
    00> [00:00:02.453,308] <dbg> wpa_supp: wpa_printf_impl: wlan0: Control interface command 'SELECT_NETWORK 0'
    00> [00:00:02.453,857] <dbg> wpa_supp: wpa_printf_impl: CTRL_IFACE: SELECT_NETWORK id=0
    00> [00:00:02.454,406] <dbg> wpa_supp: wpa_printf_impl: wlan0: Setting scan request: 0.000000 sec
    00> OK
    00> [00:00:02.455,139] <inf> wifi_mgmt_ext: Connection requested
    00> [00:00:02.456,024] <dbg> wpa_supp: wpa_printf_impl: wlan0: Starting radio work 'scan'@0x20042110 after ld.00000ld second wait
    00> [00:00:02.468,994] <dbg> wpa_supp: wpa_printf_impl: wpa_drv_zep_scan2: Scan requested - scan timeout 35 seconds
    00> 
    00> [00:00:02.469,818] <dbg> wpa_supp: wpa_printf_impl: wlan0: Already scanning - Reschedule the incoming scan req
    00> [00:00:02.470,458] <dbg> wpa_supp: wpa_printf_impl: wlan0: Setting scan request: 1.000000 sec
    00> [00:00:02.471,405] <dbg> wpa_supp: wpa_printf_impl: Passing message 47 to wpa_supplicant
    00> [00:00:02.471,954] <dbg> wpa_supp: wpa_printf_impl: wlan0: Event SCAN_STARTED (47) received
    00> [00:00:02.472,595] <dbg> wpa_supp: wpa_printf_impl: wlan0: Own scan request started a scan in ld.00000ld seconds
    00> [00:00:02.473,571] <dbg> wpa_supp: wpa_printf_impl: Received event: CTRL-EVENT-SCAN-STARTED 3
    00> 
    00> [00:00:03.471,191] <dbg> wpa_supp: wpa_printf_impl: wlan0: Already scanning - Reschedule the incoming scan req
    00> [00:00:03.471,832] <dbg> wpa_supp: wpa_printf_impl: wlan0: Setting scan request: 1.000000 sec
    00> [00:00:04.472,625] <dbg> wpa_supp: wpa_printf_impl: wlan0: Already scanning - Reschedule the incoming scan req
    00> [00:00:04.473,266] <dbg> wpa_supp: wpa_printf_impl: wlan0: Setting scan request: 1.000000 sec
    00> [00:00:05.474,060] <dbg> wpa_supp: wpa_printf_impl: wlan0: Already scanning - Reschedule the incoming scan req
    00> [00:00:05.474,700] <dbg> wpa_supp: wpa_printf_impl: wlan0: Setting scan request: 1.000000 sec
    00> [00:00:06.358,551] <dbg> wpa_supp: wpa_printf_impl: Passing message 3 to wpa_supplicant
    00> [00:00:06.359,100] <dbg> wpa_supp: wpa_printf_impl: wlan0: Event SCAN_RESULTS (3) received
    00> [00:00:06.359,710] <dbg> wpa_supp: wpa_printf_impl: wlan0: Scan completed in ld.00000ld seconds
    00> [00:00:06.363,861] <dbg> wpa_supp: wpa_printf_impl: Sorted scan results
    00> [00:00:06.364,440] <dbg> wpa_supp: wpa_printf_impl: aa:03:6f:e0:cd:78 freq=2437 qual=0 noise=-89~ level=-74 snr=15 flags=0xb age=3636 est=39000
    00> [00:00:06.368,316] <dbg> wpa_supp: wpa_printf_impl: IEs - hexdump(len=205): 00 05 61 6c 6c 65 6e 01 08 82 84 8b 96 24 30 48 6c 03 01 06 07 06 54 57 20 01 0d 1e 20 01 00 23 02 15 00 2a 01 00 32 04 0c 12 18 60 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00 2d 1a 2d 00 1b ff ff 00 00 00 00 
    00> [00:00:06.369,445] <dbg> wpa_supp: wpa_printf_impl: wlan0: BSS: Start scan result update 1
    00> [00:00:06.370,330] <dbg> wpa_supp: wpa_printf_impl: wlan0: BSS: Add new id 0 BSSID aa:03:6f:e0:cd:78 SSID 'allen' freq 2437
    00> [00:00:06.371,215] <dbg> wpa_supp: wpa_printf_impl: BSS: last_scan_res_used=zu/zu
    00> [00:00:06.371,734] <dbg> wpa_supp: wpa_printf_impl: wlan0: New scan results available (own=1 ext=0)
    00> [00:00:06.372,589] <dbg> wpa_supp: wpa_printf_impl: wlan0: Radio work 'scan'@0x20042110 done in ld.00000ld seconds
    00> [00:00:06.373,260] <dbg> wpa_supp: wpa_printf_impl: wlan0: radio_work_free('scan'@0x20042110): num_active_works --> 0
    00> [00:00:06.373,901] <dbg> wpa_supp: wpa_printf_impl: wlan0: Scan results matching the currently selected network
    00> [00:00:06.374,877] <dbg> wpa_supp: wpa_printf_impl: wlan0: 0: aa:03:6f:e0:cd:78 freq=2437 level=-74 snr=15 est_throughput=39000
    00> [00:00:06.375,518] <dbg> wpa_supp: wpa_printf_impl: wlan0: Selecting BSS from priority group 0
    00> [00:00:06.376,464] <dbg> wpa_supp: wpa_printf_impl: wlan0: 0: aa:03:6f:e0:cd:78 ssid='allen' wpa_ie_len=0 rsn_ie_len=20 caps=0x1511 level=-74 freq=2437 
    00> [00:00:06.377,227] <dbg> wpa_supp: wpa_printf_impl: wlan0:    selected based on RSN IE
    00> [00:00:06.377,990] <dbg> wpa_supp: wpa_printf_impl: wlan0:    selected BSS aa:03:6f:e0:cd:78 ssid='allen'
    00> [00:00:06.379,211] <dbg> wpa_supp: wpa_printf_impl: wlan0: Considering connect request: reassociate: 1  selected: aa:03:6f:e0:cd:78  bssid: 00:00:00:00:00:00  pending: 00:00:00:00:00:00  wpa_state: SCANNING  ssid=0x20044e80  current_ssid=0x20044e80
    00> [00:00:06.380,279] <dbg> wpa_supp: wpa_printf_impl: wlan0: Request association with aa:03:6f:e0:cd:78
    00> [00:00:06.380,889] <dbg> wpa_supp: wpa_printf_impl: wlan0: No ongoing scan/p2p-scan found to abort
    00> [00:00:06.381,500] <dbg> wpa_supp: wpa_printf_impl: wlan0: Add radio work 'sme-connect'@0x20042150
    00> [00:00:06.382,080] <dbg> wpa_supp: wpa_printf_impl: wlan0: First radio work item in the queue - schedule start immediately
    00> [00:00:06.383,117] <dbg> wpa_supp: wpa_printf_impl: wlan0: Starting radio work 'sme-connect'@0x20042150 after ld.00000ld second wait
    00> [00:00:06.383,758] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: clearing own WPA/RSN IE
    00> [00:00:06.384,277] <dbg> wpa_supp: wpa_printf_impl: wlan0: RSN: clearing own RSNXE
    00> [00:00:06.384,826] <dbg> wpa_supp: wpa_printf_impl: wlan0: Automatic auth_alg selection: 0x1
    00> [00:00:06.385,406] <dbg> wpa_supp: wpa_printf_impl: wlan0: RSN: using IEEE 802.11i/D9.0
    00> [00:00:06.386,047] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: Selected cipher suites: group 16 pairwise 16 key_mgmt 2 proto 2
    00> [00:00:06.386,688] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: Selected mgmt group cipher 32
    00> [00:00:06.387,237] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: clearing AP WPA IE
    00> [00:00:06.388,092] <dbg> wpa_supp: wpa_printf_impl: WPA: set AP RSN IE - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00
    00> [00:00:06.388,763] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: clearing AP RSNXE
    00> [00:00:06.389,373] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: AP group 0x10 network profile group 0x18; available group 0x10
    00> [00:00:06.390,014] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: using GTK CCMP
    00> [00:00:06.390,594] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: AP pairwise 0x10 network profile pairwise 0x18; available pairwise 0x10
    00> [00:00:06.391,265] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: using PTK CCMP
    00> [00:00:06.391,845] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: AP key_mgmt 0x2 network profile key_mgmt 0x2; available key_mgmt 0x2
    00> [00:00:06.392,486] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: using KEY_MGMT WPA-PSK
    00> [00:00:06.393,127] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: AP mgmt_group_cipher 0x20 network profile mgmt_group_cipher 0x0; available mgmt_group_cipher 0x0
    00> [00:00:06.393,829] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: not using MGMT group cipher
    00> [00:00:06.394,714] <dbg> wpa_supp: wpa_printf_impl: WPA: Set own WPA IE default - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 80 00
    00> [00:00:06.395,385] <dbg> wpa_supp: wpa_printf_impl: RSN: Set own RSNXE default - hexdump(len=0):
    00> [00:00:06.395,904] <dbg> wpa_supp: wpa_printf_impl: PSK (set in config) - hexdump(len=32): [REMOVED]
    00> [00:00:06.396,392] <dbg> wpa_supp: wpa_printf_impl: WPA: Set PMK based on external data - hexdump(len=32): [REMOVED]
    00> [00:00:06.396,942] <dbg> wpa_supp: wpa_printf_impl: RRM: Determining whether RRM can be used - device support: 0x50
    00> [00:00:06.397,460] <dbg> wpa_supp: wpa_printf_impl: RRM: No RRM in network
    00> [00:00:06.398,986] <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:00:06.399,688] <dbg> wpa_supp: wpa_printf_impl: wlan0: Cancelling scan request
    00> [00:00:06.400,421] <inf> wpa_supp: wlan0: SME: Trying to authenticate with aa:03:6f:e0:cd:78 (SSID='allen' freq=2437 MHz)
    00> [00:00:06.401,153] <dbg> wpa_supp: wpa_printf_impl: wlan0: State: SCANNING -> AUTHENTICATING
    00> [00:00:06.408,508] <dbg> wpa_supp: wpa_printf_impl: Received event: CTRL-EVENT-BSS-ADDED 0 aa:03:6f:e0:cd:78
    00> 
    00> [00:00:08.739,044] <dbg> wpa_supp: wpa_printf_impl: Passing message 10 to wpa_supplicant
    00> [00:00:08.739,593] <dbg> wpa_supp: wpa_printf_impl: wlan0: Event AUTH (10) received
    00> [00:00:08.740,417] <dbg> wpa_supp: wpa_printf_impl: wlan0: SME: Authentication response: peer=aa:03:6f:e0:cd:78 auth_type=0 auth_transaction=2 status_code=0
    00> [00:00:08.741,271] <dbg> wpa_supp: wpa_printf_impl: SME: Authentication response IEs - hexdump(len=12): dd 0a 00 10 18 02 00 00 1c 00 00 02
    00> [00:00:08.742,645] <dbg> wpa_supp: wpa_printf_impl: SME: Association Request IEs - hexdump(len=45): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 80 00 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:00:08.743,743] <inf> wpa_supp: wlan0: Trying to associate with aa:03:6f:e0:cd:78 (SSID='allen' freq=2437 MHz)
    00> [00:00:08.744,445] <dbg> wpa_supp: wpa_printf_impl: wlan0: State: AUTHENTICATING -> ASSOCIATING
    00> [00:00:08.751,525] <dbg> wpa_supp: wpa_printf_impl: WPA: set own WPA/RSN IE - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 80 00
    00> [00:00:08.752,197] <dbg> wpa_supp: wpa_printf_impl: wlan0: RSN: clearing own RSNXE
    00> [00:00:08.774,566] <dbg> wpa_supp: wpa_printf_impl: Event: -805240830
    00> [00:00:08.779,510] <dbg> wpa_supp: wpa_printf_impl: Passing message 0 to wpa_supplicant
    00> [00:00:08.780,059] <dbg> wpa_supp: wpa_printf_impl: wlan0: Event ASSOC (0) received
    00> [00:00:08.780,578] <dbg> wpa_supp: wpa_printf_impl: wlan0: Association info event
    00> [00:00:08.783,691] <dbg> wpa_supp: wpa_printf_impl: req_ies - hexdump(len=169): 00 05 61 6c 6c 65 6e 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 24 1c 01 01 02 01 03 01 04 01 05 01 06 01 07 01 08 01 09 01 0a 01 0b 01 0c 01 0d 01 0e 01 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04
    00> [00:00:08.787,109] <dbg> wpa_supp: wpa_printf_impl: resp_ies - hexdump(len=155): 01 08 82 84 8b 96 24 30 48 6c 32 04 0c 12 18 60 2d 1a 2d 00 1b ff ff 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 3d 16 06 00 04 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 7f 08 04 00 00 00 00 0
    00> [00:00:08.788,269] <dbg> wpa_supp: wpa_printf_impl: unknown vendor specific information element ignored (vendor OUI 00:17:f2 len=10)
    00> [00:00:08.788,879] <dbg> wpa_supp: wpa_printf_impl: unknown vendor specific information element ignored (vendor OUI 00:17:f2 len=25)
    00> [00:00:08.789,459] <dbg> wpa_supp: wpa_printf_impl: unknown vendor specific information element ignored (vendor OUI 00:10:18 len=10)
    00> [00:00:08.790,130] <dbg> wpa_supp: wpa_printf_impl: unknown vendor specific information element ignored (vendor OUI 00:17:f2 len=10)
    00> [00:00:08.790,740] <dbg> wpa_supp: wpa_printf_impl: unknown vendor specific information element ignored (vendor OUI 00:17:f2 len=25)
    00> [00:00:08.791,320] <dbg> wpa_supp: wpa_printf_impl: unknown vendor specific information element ignored (vendor OUI 00:10:18 len=10)
    00> [00:00:08.791,931] <dbg> wpa_supp: wpa_printf_impl: wlan0: freq=2437 MHz
    00> [00:00:08.792,572] <dbg> wpa_supp: wpa_printf_impl: unknown vendor specific information element ignored (vendor OUI 00:17:f2 len=10)
    00> [00:00:08.793,182] <dbg> wpa_supp: wpa_printf_impl: unknown vendor specific information element ignored (vendor OUI 00:17:f2 len=25)
    00> [00:00:08.793,792] <dbg> wpa_supp: wpa_printf_impl: unknown vendor specific information element ignored (vendor OUI 00:10:18 len=10)
    00> [00:00:08.794,738] <dbg> wpa_supp: wpa_printf_impl: WPA: set own WPA/RSN IE - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 80 00
    00> [00:00:08.795,440] <dbg> wpa_supp: wpa_printf_impl: wlan0: RSN: clearing own RSNXE
    00> [00:00:08.796,020] <dbg> wpa_supp: wpa_printf_impl: wlan0: State: ASSOCIATING -> ASSOCIATED
    00> [00:00:08.803,558] <dbg> wpa_supp: wpa_printf_impl: wlan0: Associated to a new BSS: BSSID=aa:03:6f:e0:cd:78
    00> [00:00:08.804,107] <dbg> wpa_supp: wpa_printf_impl: wpa_drv_zep_get_ssid: SSID size: 5
    00> 
    00> [00:00:08.804,748] <inf> wpa_supp: wlan0: Associated with aa:03:6f:e0:cd:78
    00> [00:00:08.805,297] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: Association event - clear replay counter
    00> [00:00:08.805,847] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: Clear old PTK
    00> [00:00:08.806,518] <dbg> wpa_supp: wpa_printf_impl: wlan0: Setting authentication timeout: 10 sec 0 usec
    00> [00:00:08.807,098] <dbg> wpa_supp: wpa_printf_impl: wlan0: Cancelling scan request
    00> [00:00:08.807,647] <inf> wpa_supp: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
    00> [00:00:08.811,889] <dbg> wpa_supp: wpa_printf_impl: wlan0: RX EAPOL from aa:03:6f:e0:cd:78
    00> [00:00:08.813,964] <dbg> wpa_supp: wpa_printf_impl: RX EAPOL - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 dd 27 9f 80 de 0c 4b 04 05 a1 61 53 d6 b3 e3 7e c0 31 66 cc 88 12 a7 cb 3c d2 34 de 6e cb 0d a5 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
    00> [00:00:08.815,093] <dbg> wpa_supp: wpa_printf_impl: wlan0: Setting authentication timeout: 10 sec 0 usec
    00> [00:00:08.815,765] <dbg> wpa_supp: wpa_printf_impl: wlan0: IEEE 802.1X RX: version=2 type=3 length=95
    00> [00:00:08.817,840] <dbg> wpa_supp: wpa_printf_impl: WPA: RX EAPOL-Key - hexdump(len=99): 02 03 00 5f 02 00 8a 00 10 00 00 00 00 00 00 00 01 dd 27 9f 80 de 0c 4b 04 05 a1 61 53 d6 b3 e3 7e c0 31 66 cc 88 12 a7 cb 3c d2 34 de 6e cb 0d a5 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
    00> [00:00:08.818,939] <dbg> wpa_supp: wpa_printf_impl: wlan0:   EAPOL-Key type=2
    00> [00:00:08.819,671] <dbg> wpa_supp: wpa_printf_impl: wlan0:   key_info 0x8a (ver=2 keyidx=0 rsvd=0 Pairwise Ack)
    00> [00:00:08.820,343] <dbg> wpa_supp: wpa_printf_impl: wlan0:   key_length=16 key_data_length=0
    00> [00:00:08.821,075] <dbg> wpa_supp: wpa_printf_impl:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01
    00> [00:00:08.822,113] <dbg> wpa_supp: wpa_printf_impl:   key_nonce - hexdump(len=32): dd 27 9f 80 de 0c 4b 04 05 a1 61 53 d6 b3 e3 7e c0 31 66 cc 88 12 a7 cb 3c d2 34 de 6e cb 0d a5
    00> [00:00:08.823,059] <dbg> wpa_supp: wpa_printf_impl:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
    00> [00:00:08.823,760] <dbg> wpa_supp: wpa_printf_impl:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
    00> [00:00:08.824,432] <dbg> wpa_supp: wpa_printf_impl:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
    00> [00:00:08.825,225] <dbg> wpa_supp: wpa_printf_impl:   key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
    00> [00:00:08.825,805] <dbg> wpa_supp: wpa_printf_impl: wpa_drv_zep_get_ssid: SSID size: 5
    00> 
    00> [00:00:08.826,324] <dbg> wpa_supp: wpa_printf_impl: wlan0: State: ASSOCIATED -> 4WAY_HANDSHAKE
    00> [00:00:08.827,087] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: RX message 1 of 4-Way Handshake from aa:03:6f:e0:cd:78 (ver=2)
    00> [00:00:08.827,697] <dbg> wpa_supp: wpa_printf_impl: RSN: msg 1/4 key data - hexdump(len=0):
    00> [00:00:08.828,918] <dbg> wpa_supp: wpa_printf_impl: WPA: Renewed SNonce - hexdump(len=32): 90 19 e1 49 13 28 35 31 a0 4a 4e a6 39 59 45 da fc f2 2c db b2 85 7e 88 8c 35 71 54 8f 78 b0 68
    00> [00:00:08.829,589] <dbg> wpa_supp: wpa_printf_impl: WPA: PTK derivation using PRF(SHA1)
    00> [00:00:08.831,573] <dbg> wpa_supp: wpa_printf_impl: WPA: PTK derivation - A1=f0:ce:36:00:00:4a A2=aa:03:6f:e0:cd:78
    00> [00:00:08.832,611] <dbg> wpa_supp: wpa_printf_impl: WPA: Nonce1 - hexdump(len=32): 90 19 e1 49 13 28 35 31 a0 4a 4e a6 39 59 45 da fc f2 2c db b2 85 7e 88 8c 35 71 54 8f 78 b0 68
    00> [00:00:08.833,801] <dbg> wpa_supp: wpa_printf_impl: WPA: Nonce2 - hexdump(len=32): dd 27 9f 80 de 0c 4b 04 05 a1 61 53 d6 b3 e3 7e c0 31 66 cc 88 12 a7 cb 3c d2 34 de 6e cb 0d a5
    00> [00:00:08.834,503] <dbg> wpa_supp: wpa_printf_impl: WPA: PMK - hexdump(len=32): [REMOVED]
    00> [00:00:08.834,960] <dbg> wpa_supp: wpa_printf_impl: WPA: PTK - hexdump(len=48): [REMOVED]
    00> [00:00:08.835,418] <dbg> wpa_supp: wpa_printf_impl: WPA: KCK - hexdump(len=16): [REMOVED]
    00> [00:00:08.835,906] <dbg> wpa_supp: wpa_printf_impl: WPA: KEK - hexdump(len=16): [REMOVED]
    00> [00:00:08.836,364] <dbg> wpa_supp: wpa_printf_impl: WPA: TK - hexdump(len=16): [REMOVED]
    00> [00:00:08.837,188] <dbg> wpa_supp: wpa_printf_impl: WPA: WPA IE for msg 2/4 - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 80 00
    00> [00:00:08.838,012] <dbg> wpa_supp: wpa_printf_impl: WPA: Replay Counter - hexdump(len=8): 00 00 00 00 00 00 00 01
    00> [00:00:08.838,592] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: Sending EAPOL-Key 2/4
    00> [00:00:08.839,172] <dbg> wpa_supp: wpa_printf_impl: WPA: Send EAPOL-Key frame to aa:03:6f:e0:cd:78 ver=2 mic_len=16 key_mgmt=0x2
    00> [00:00:08.839,691] <dbg> wpa_supp: wpa_printf_impl: WPA: EAPOL-Key MIC using HMAC-SHA1
    00> [00:00:08.840,667] <dbg> wpa_supp: wpa_printf_impl: WPA: KCK - hexdump(len=16): [REMOVED]
    00> [00:00:08.841,400] <dbg> wpa_supp: wpa_printf_impl: WPA: Derived Key MIC - hexdump(len=16): 9b 7d 72 2b a6 1c d1 18 df a7 86 8e 49 45 17 d9
    00> [00:00:08.843,872] <dbg> wpa_supp: wpa_printf_impl: WPA: TX EAPOL-Key - hexdump(len=121): 01 03 00 75 02 01 0a 00 00 00 00 00 00 00 00 00 01 90 19 e1 49 13 28 35 31 a0 4a 4e a6 39 59 45 da fc f2 2c db b2 85 7e 88 8c 35 71 54 8f 78 b0 68 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0
    00> [00:00:08.875,213] <dbg> wpa_supp: wpa_printf_impl: wlan0: RX EAPOL from aa:03:6f:e0:cd:78
    00> [00:00:08.878,051] <dbg> wpa_supp: wpa_printf_impl: RX EAPOL - hexdump(len=155): 02 03 00 97 02 13 ca 00 10 00 00 00 00 00 00 00 02 dd 27 9f 80 de 0c 4b 04 05 a1 61 53 d6 b3 e3 7e c0 31 66 cc 88 12 a7 cb 3c d2 34 de 6e cb 0d a5 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1d 00 00 00 00 00 00 00 00 00 0
    00> [00:00:08.879,211] <dbg> wpa_supp: wpa_printf_impl: wlan0: IEEE 802.1X RX: version=2 type=3 length=151
    00> [00:00:08.882,141] <dbg> wpa_supp: wpa_printf_impl: WPA: RX EAPOL-Key - hexdump(len=155): 02 03 00 97 02 13 ca 00 10 00 00 00 00 00 00 00 02 dd 27 9f 80 de 0c 4b 04 05 a1 61 53 d6 b3 e3 7e c0 31 66 cc 88 12 a7 cb 3c d2 34 de 6e cb 0d a5 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 1d 00 00 00 00 00 00 0
    00> [00:00:08.883,270] <dbg> wpa_supp: wpa_printf_impl: wlan0:   EAPOL-Key type=2
    00> [00:00:08.884,033] <dbg> wpa_supp: wpa_printf_impl: wlan0:   key_info 0x13ca (ver=2 keyidx=0 rsvd=0 Pairwise Install Ack MIC Secure Encr)
    00> [00:00:08.884,704] <dbg> wpa_supp: wpa_printf_impl: wlan0:   key_length=16 key_data_length=56
    00> [00:00:08.885,375] <dbg> wpa_supp: wpa_printf_impl:   replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02
    00> [00:00:08.886,383] <dbg> wpa_supp: wpa_printf_impl:   key_nonce - hexdump(len=32): dd 27 9f 80 de 0c 4b 04 05 a1 61 53 d6 b3 e3 7e c0 31 66 cc 88 12 a7 cb 3c d2 34 de 6e cb 0d a5
    00> [00:00:08.887,359] <dbg> wpa_supp: wpa_printf_impl:   key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
    00> [00:00:08.888,061] <dbg> wpa_supp: wpa_printf_impl:   key_rsc - hexdump(len=8): 1d 00 00 00 00 00 00 00
    00> [00:00:08.888,702] <dbg> wpa_supp: wpa_printf_impl:   key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00
    00> [00:00:08.889,495] <dbg> wpa_supp: wpa_printf_impl:   key_mic - hexdump(len=16): 50 7f da 1c fc af 17 2f 46 7e ef 9f cd 41 05 59
    00> [00:00:08.890,045] <dbg> wpa_supp: wpa_printf_impl: WPA: EAPOL-Key MIC using HMAC-SHA1
    00> [00:00:08.891,967] <dbg> wpa_supp: wpa_printf_impl: RSN: encrypted key data - hexdump(len=56): 32 9c c3 6d 29 6d 90 1e 5c 15 f6 c2 fb 98 21 98 6a 31 ce 6d c1 56 a1 c6 9b 8b 5f 1d 64 a5 44 cf 10 f3 fc a6 e2 4c 83 1a a8 77 48 44 06 a5 a4 84 50 10 b3 16 fe 39 5c 2f
    00> [00:00:08.892,852] <dbg> wpa_supp: wpa_printf_impl: WPA: Decrypt Key Data using AES-UNWRAP (KEK length 16)
    00> [00:00:08.894,958] <dbg> wpa_supp: wpa_printf_impl: WPA: decrypted EAPOL-Key key data - hexdump(len=48): [REMOVED]
    00> [00:00:08.895,568] <dbg> wpa_supp: wpa_printf_impl: wlan0: State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
    00> [00:00:08.896,301] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: RX message 3 of 4-Way Handshake from aa:03:6f:e0:cd:78 (ver=2)
    00> [00:00:08.897,644] <dbg> wpa_supp: wpa_printf_impl: WPA: IE KeyData - hexdump(len=48): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00 dd 16 00 0f ac 01 01 00 73 64 ea 26 a0 1a 96 d5 75 96 c7 91 59 ae a3 2f dd 00
    00> [00:00:08.898,864] <dbg> wpa_supp: wpa_printf_impl: WPA: RSN IE in EAPOL-Key - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00
    00> [00:00:08.899,536] <dbg> wpa_supp: wpa_printf_impl: WPA: GTK in EAPOL-Key - hexdump(len=24): [REMOVED]
    00> [00:00:08.900,115] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: Sending EAPOL-Key 4/4
    00> [00:00:08.900,695] <dbg> wpa_supp: wpa_printf_impl: WPA: Send EAPOL-Key frame to aa:03:6f:e0:cd:78 ver=2 mic_len=16 key_mgmt=0x2
    00> [00:00:08.901,214] <dbg> wpa_supp: wpa_printf_impl: WPA: EAPOL-Key MIC using HMAC-SHA1
    00> [00:00:08.902,130] <dbg> wpa_supp: wpa_printf_impl: WPA: KCK - hexdump(len=16): [REMOVED]
    00> [00:00:08.902,862] <dbg> wpa_supp: wpa_printf_impl: WPA: Derived Key MIC - hexdump(len=16): 03 87 68 85 91 63 af a2 d6 0d 78 97 26 ba 3a bb
    00> [00:00:08.905,059] <dbg> wpa_supp: wpa_printf_impl: WPA: TX EAPOL-Key - hexdump(len=99): 01 03 00 5f 02 03 0a 00 00 00 00 00 00 00 00 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
    00> [00:00:08.906,555] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: Installing PTK to the driver
    00> [00:00:08.907,196] <dbg> wpa_supp: wpa_printf_impl: _wpa_drv_zep_set_key: priv:0x20002250 alg 3 addr 0x2004451c key_idx 0 set_tx 1 seq 0x9901a seq_len 6 key 0x20044164 key_len 16
    00> 
    00> [00:00:08.916,442] <dbg> wpa_supp: wpa_printf_impl: wlan0: State: 4WAY_HANDSHAKE -> GROUP_HANDSHAKE
    00> [00:00:08.916,992] <dbg> wpa_supp: wpa_printf_impl: RSN: received GTK in pairwise handshake - hexdump(len=18): [REMOVED]
    00> [00:00:08.917,541] <dbg> wpa_supp: wpa_printf_impl: WPA: Group Key - hexdump(len=16): [REMOVED]
    00> [00:00:08.918,151] <dbg> wpa_supp: wpa_printf_impl: wlan0: WPA: Installing GTK to the driver (keyidx=1 tx=0 len=16)
    00> [00:00:08.918,823] <dbg> wpa_supp: wpa_printf_impl: WPA: RSC - hexdump(len=6): 1d 00 00 00 00 00
    00> [00:00:08.919,433] <dbg> wpa_supp: wpa_printf_impl: _wpa_drv_zep_set_key: priv:0x20002250 alg 3 addr 0x93116 key_idx 1 set_tx 0 seq 0x20042681 seq_len 6 key 0x20014414 key_len 16
    00> 
    00> [00:00:08.924,926] <inf> wpa_supp: wlan0: WPA: Key negotiation completed with aa:03:6f:e0:cd:78 [PTK=CCMP GTK=CCMP]
    00> [00:00:08.925,598] <dbg> wpa_supp: wpa_printf_impl: wlan0: Cancelling authentication timeout
    00> [00:00:08.926,177] <dbg> wpa_supp: wpa_printf_impl: wlan0: State: GROUP_HANDSHAKE -> COMPLETED
    00> [00:00:08.926,879] <dbg> wpa_supp: wpa_printf_impl: wlan0: Radio work 'sme-connect'@0x20042150 done in ld.00000ld seconds
    00> [00:00:08.928,344] <dbg> wpa_supp: wpa_printf_impl: wlan0: radio_work_free('sme-connect'@0x20042150): num_active_works --> 0
    00> [00:00:08.929,199] <inf> wpa_supp: wlan0: CTRL-EVENT-CONNECTED - Connection to aa:03:6f:e0:cd:78 completed [id=0 id_str=]
    00> [00:00:08.937,957] <dbg> wpa_supp: wpa_printf_impl: DHCP WAR: TX frame res=30
    00> [00:00:09.378,387] <inf> network: Network connectivity established
    00> 
    00> [00:00:14.380,096] <dbg> mqtt_helper: broker_init: Resolving IP address for test.mosquitto.org
    00> [00:00:15.992,553] <dbg> mqtt_helper: broker_init: IPv4 Address found 91.121.93.94 (AF_INET)
    00> [00:00:15.993,011] <dbg> mqtt_helper: mqtt_state_set: State transition: MQTT_STATE_DISCONNECTED --> MQTT_STATE_TRANSPORT_CONNECTING
    00> [00:00:16.344,055] <dbg> mqtt_helper: mqtt_state_set: State transition: MQTT_STATE_TRANSPORT_CONNECTING --> MQTT_STATE_TRANSPORT_CONNECTED
    00> [00:00:16.344,573] <dbg> mqtt_helper: mqtt_state_set: State transition: MQTT_STATE_TRANSPORT_CONNECTED --> MQTT_STATE_CONNECTING
    00> [00:00:16.345,062] <dbg> mqtt_helper: client_connect: Using send socket timeout of 60 seconds
    00> [00:00:16.345,458] <dbg> mqtt_helper: mqtt_helper_connect: MQTT connection request sent
    00> [00:00:16.350,036] <dbg> mqtt_helper: mqtt_helper_poll_loop: Took connection_poll_sem
    00> [00:00:16.350,402] <dbg> mqtt_helper: mqtt_helper_poll_loop: Starting to poll on socket, fd: 9
    00> [00:00:16.350,982] <dbg> mqtt_helper: mqtt_helper_poll_loop: Polling on socket fd: 9
    00> [00:00:16.712,432] <dbg> mqtt_helper: mqtt_evt_handler: MQTT mqtt_client connected
    00> [00:00:16.712,829] <dbg> mqtt_helper: mqtt_state_set: State transition: MQTT_STATE_CONNECTING --> MQTT_STATE_CONNECTED
    00> [00:00:16.713,287] <inf> transport: Connected to MQTT broker
    00> [00:00:16.714,630] <inf> transport: Hostname: test.mosquitto.org
    00> [00:00:16.714,935] <inf> transport: Client ID: C6D095F19440DF6D
    00> [00:00:16.715,270] <inf> transport: Port: 1883
    00> [00:00:16.715,545] <inf> transport: TLS: No
    00> [00:00:16.715,820] <inf> transport: Subscribing to: C6D095F19440DF6D/my/subscribe/topic
    00> [00:00:16.716,217] <dbg> mqtt_helper: mqtt_helper_subscribe: Subscribing to: C6D095F19440DF6D/my/subscribe/topic
    00> [00:00:16.717,651] <dbg> mqtt_helper: mqtt_helper_poll_loop: Polling on socket fd: 9
    00> [00:00:17.028,442] <dbg> mqtt_helper: mqtt_evt_handler: MQTT_EVT_SUBACK: id = 2469 result = 0
    00> [00:00:17.028,869] <inf> transport: Subscribed to topic C6D095F19440DF6D/my/subscribe/topic
    00> [00:00:17.029,266] <dbg> mqtt_helper: mqtt_helper_poll_loop: Polling on socket fd: 9
    00> [00:01:00.474,578] <dbg> mqtt_helper: mqtt_helper_publish: Publishing to topic: C6D095F19440DF6D/my/publish/topic
    00> [00:01:00.476,074] <inf> transport: Published message: "Hello MQTT! Current uptime is: 60474" on topic: "C6D095F19440DF6D/my/publish/topic"
    00> [00:01:01.158,203] <dbg> mqtt_helper: mqtt_evt_handler: MQTT_EVT_PUBACK: id = 60474 result = 0
    00> [00:01:01.158,630] <dbg> mqtt_helper: mqtt_helper_poll_loop: Polling on socket fd: 9
    00> [00:02:00.474,670] <dbg> mqtt_helper: mqtt_helper_publish: Publishing to topic: C6D095F19440DF6D/my/publish/topic
    00> [00:02:00.476,165] <inf> transport: Published message: "Hello MQTT! Current uptime is: 120474" on topic: "C6D095F19440DF6D/my/publish/topic"
    00> [00:02:00.477,996] <dbg> mqtt_helper: mqtt_helper_poll_loop: Polling on socket fd: 9
    00> [00:02:01.065,643] <dbg> mqtt_helper: mqtt_evt_handler: MQTT_EVT_PUBACK: id = 54938 result = 0
    00> [00:02:01.066,070] <dbg> mqtt_helper: mqtt_helper_poll_loop: Polling on socket fd: 9

    Regards,

    Allen

  • Hi Allen,

    allenyang said:
    But if I want to use for formal purposes, how can I set mac address correctly?

    What do you refer to as formal purposes? If you mean non-testing purposes, you could send us your full project and we could further look into it. Please let me know if you have any private information inside your project which you do not want to be publicly visible. If this is the case, I can make this case private.

    What is shown in your last log? Which configuration did you use in that case?

    Best regards,
    Dejan


  • Hi, 

    I just tested on MQTT sample, so there is no private information, I can send my full project. Thanks for the help.

    mqtt_2.rar

    I want to use for product. Users need to set mac address by themself when first time use. If the "set mac address" function is success, then I choose this way, or I can use the way of OTP.

    I know that it can set mac address with OTP by using the command in radio_test sample with UART interface. Is there any api to use for calling in program?

    "What is shown in your last log? Which configuration did you use in that case?"

    It is successful running program of Custom board by using  this "CONFIG_WIFI_FIXED_MAC_ADDRESS, compared to failed program, it continued the log after 39th line.

    Regards,

    Allen

  • Hi Allen,

    Thank you for sharing your project. We will look into it internally. Please note that I will be out of the office for around two weeks and replies might get delayed.

    Best regards,
    Dejan

Reply Children
Related