2020-09-05 17:47:07 export USER=root 2020-09-05 17:47:07 ./build.sh Building TNC testing tools Building wlantest Building hs20-osu-client Building hostapd Building wpa_supplicant wpa_cli.c: In function ‘wpa_cli_cmd_all_bss’: wpa_cli.c:3105:3: warning: ‘ssid’ may be used uninitialized in this function [-Wmaybe-uninitialized] printf("%s\t%s\t%s\t%s\t%s\n", bssid, freq, level, flags, ssid); ^~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ wpa_cli.c:3064:55: note: ‘ssid’ was declared here char buf[512], *pos, *bssid, *freq, *level, *flags, *ssid; ^~~~ wpa_cli.c:3105:3: warning: ‘flags’ may be used uninitialized in this function [-Wmaybe-uninitialized] printf("%s\t%s\t%s\t%s\t%s\n", bssid, freq, level, flags, ssid); ^~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ wpa_cli.c:3064:47: note: ‘flags’ was declared here char buf[512], *pos, *bssid, *freq, *level, *flags, *ssid; ^~~~~ wpa_cli.c:3105:3: warning: ‘level’ may be used uninitialized in this function [-Wmaybe-uninitialized] printf("%s\t%s\t%s\t%s\t%s\n", bssid, freq, level, flags, ssid); ^~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ wpa_cli.c:3064:39: note: ‘level’ was declared here char buf[512], *pos, *bssid, *freq, *level, *flags, *ssid; ^~~~~ wpa_cli.c:3105:3: warning: ‘freq’ may be used uninitialized in this function [-Wmaybe-uninitialized] printf("%s\t%s\t%s\t%s\t%s\n", bssid, freq, level, flags, ssid); ^~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ wpa_cli.c:3064:32: note: ‘freq’ was declared here char buf[512], *pos, *bssid, *freq, *level, *flags, *ssid; ^~~~ wpa_cli.c:3105:3: warning: ‘bssid’ may be used uninitialized in this function [-Wmaybe-uninitialized] printf("%s\t%s\t%s\t%s\t%s\n", bssid, freq, level, flags, ssid); ^~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~ wpa_cli.c:3064:24: note: ‘bssid’ was declared here char buf[512], *pos, *bssid, *freq, *level, *flags, *ssid; ^~~~~ 2020-09-05 17:47:39 ./start.sh 2020-09-05 17:47:40 ./run-tests.py erp_radius DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START erp_radius 1/1 Test: ERP enabled on RADIUS server and peer Starting AP as-erp Starting AP wlan3 Connect STA wlan0 to AP PASS erp_radius 0.329997 2020-09-05 17:47:41.946356 passed all 1 test case(s) 2020-09-05 17:47:41 ./run-tests.py erp_radius_eap_methods DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START erp_radius_eap_methods 1/1 Test: ERP enabled on RADIUS server and peer Starting AP as-erp Starting AP wlan3 Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP Connect STA wlan0 to AP PASS erp_radius_eap_methods 2.097134 2020-09-05 17:47:44.177751 passed all 1 test case(s) 2020-09-05 17:47:44 ./run-tests.py erp_radius_eap_tls_v13 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START erp_radius_eap_tls_v13 1/1 Test: ERP enabled on RADIUS server and peer using EAP-TLS v1.3 Starting AP as-erp Starting AP wlan3 Connect STA wlan0 to AP PASS erp_radius_eap_tls_v13 0.226891 2020-09-05 17:47:44.528522 passed all 1 test case(s) 2020-09-05 17:47:44 ./run-tests.py erp_radius_ext DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START erp_radius_ext 1/1 Test: ERP enabled on a separate RADIUS server and peer Starting AP wlan3 Connect STA wlan0 to AP PASS erp_radius_ext 0.306549 2020-09-05 17:47:44.967670 passed all 1 test case(s) 2020-09-05 17:47:44 ./run-tests.py erp_radius_no_wildcard_user DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START erp_radius_no_wildcard_user 1/1 Test: ERP enabled on RADIUS server and peer and no wildcard user Starting AP as-erp Starting AP wlan3 Connect STA wlan0 to AP PASS erp_radius_no_wildcard_user 0.303199 2020-09-05 17:47:45.405114 passed all 1 test case(s) 2020-09-05 17:47:45 ./run-tests.py erp_server_no_match DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START erp_server_no_match 1/1 Test: ERP enabled on server and peer, but server has no key match Starting AP wlan3 Connect STA wlan0 to AP PASS erp_server_no_match 0.255558 2020-09-05 17:47:45.787689 passed all 1 test case(s) 2020-09-05 17:47:45 ./run-tests.py ext_password_eap DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START ext_password_eap 1/1 Test: External password storage for EAP password Starting AP wlan3 Connect STA wlan0 to AP PASS ext_password_eap 0.178059 2020-09-05 17:47:46.098978 passed all 1 test case(s) 2020-09-05 17:47:46 ./run-tests.py ext_password_interworking DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START ext_password_interworking 1/1 Test: External password storage for Interworking network selection Starting AP wlan3 PASS ext_password_interworking 0.225177 2020-09-05 17:47:46.449353 passed all 1 test case(s) 2020-09-05 17:47:46 ./run-tests.py ext_password_psk DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START ext_password_psk 1/1 Test: External password storage for PSK Starting AP wlan3 Connect STA wlan0 to AP PASS ext_password_psk 0.181825 2020-09-05 17:47:46.756949 passed all 1 test case(s) 2020-09-05 17:47:46 ./run-tests.py ext_password_psk_not_found DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START ext_password_psk_not_found 1/1 Test: External password storage for PSK and PSK not found Starting AP wlan3 Connect STA wlan0 to AP Connect STA wlan1 to AP Connect STA wlan2 to AP Connect STA wlan5 to AP PASS ext_password_psk_not_found 1.471326 2020-09-05 17:47:48.353711 passed all 1 test case(s) 2020-09-05 17:47:48 ./run-tests.py ext_radio_work DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START ext_radio_work 1/1 Test: External radio work item PASS ext_radio_work 1.032866 2020-09-05 17:47:49.520009 passed all 1 test case(s) 2020-09-05 17:47:49 ./run-tests.py ext_radio_work_disconnect_connect DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START ext_radio_work_disconnect_connect 1/1 Test: External radio work and DISCONNECT clearing connection attempt Starting AP wlan3 Connect STA wlan0 to AP PASS ext_radio_work_disconnect_connect 0.626523 2020-09-05 17:47:50.276579 passed all 1 test case(s) 2020-09-05 17:47:50 ./run-tests.py fils_and_ft DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_and_ft 1/1 Test: FILS SK using ERP and FT initial mobility domain association Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP Starting AP wlan4 (old add_ap argument type) PASS fils_and_ft 0.644678 2020-09-05 17:47:51.050109 passed all 1 test case(s) 2020-09-05 17:47:51 ./run-tests.py fils_and_ft_over_air DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_and_ft_over_air 1/1 Test: FILS SK using ERP and FT-over-air (SHA256) Starting AP as-erp Set up ERP key hierarchy without FILS/FT authentication Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP Initial mobility domain association using FILS authentication Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) FT protocol using FT key hierarchy established during FILS authentication FT protocol using the previously established FT key hierarchy from FILS authentication PASS fils_and_ft_over_air 0.989455 2020-09-05 17:47:52.166366 passed all 1 test case(s) 2020-09-05 17:47:52 ./run-tests.py fils_and_ft_over_air_sha384 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_and_ft_over_air_sha384 1/1 Test: FILS SK using ERP and FT-over-air (SHA384) Starting AP as-erp Set up ERP key hierarchy without FILS/FT authentication Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP Initial mobility domain association using FILS authentication Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) FT protocol using FT key hierarchy established during FILS authentication FT protocol using the previously established FT key hierarchy from FILS authentication PASS fils_and_ft_over_air_sha384 0.972827 2020-09-05 17:47:53.267643 passed all 1 test case(s) 2020-09-05 17:47:53 ./run-tests.py fils_and_ft_over_ds DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_and_ft_over_ds 1/1 Test: FILS SK using ERP and FT-over-DS (SHA256) Starting AP as-erp Set up ERP key hierarchy without FILS/FT authentication Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP Initial mobility domain association using FILS authentication Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) FT protocol using FT key hierarchy established during FILS authentication FT protocol using the previously established FT key hierarchy from FILS authentication PASS fils_and_ft_over_ds 0.822279 2020-09-05 17:47:54.215438 passed all 1 test case(s) 2020-09-05 17:47:54 ./run-tests.py fils_and_ft_over_ds_sha384 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_and_ft_over_ds_sha384 1/1 Test: FILS SK using ERP and FT-over-DS (SHA384) Starting AP as-erp Set up ERP key hierarchy without FILS/FT authentication Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP Initial mobility domain association using FILS authentication Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) FT protocol using FT key hierarchy established during FILS authentication FT protocol using the previously established FT key hierarchy from FILS authentication PASS fils_and_ft_over_ds_sha384 0.82017 2020-09-05 17:47:55.162694 passed all 1 test case(s) 2020-09-05 17:47:55 ./run-tests.py fils_assoc_replay DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_assoc_replay 1/1 Test: FILS AP and replayed Association Request frame Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP Replay the last Association Request frame CCMP PN: ['0x000000000001', '0x000000000002'] PASS fils_assoc_replay 0.555065 2020-09-05 17:47:55.844474 passed all 1 test case(s) 2020-09-05 17:47:55 ./run-tests.py fils_auth_gtk_rekey DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_auth_gtk_rekey 1/1 Test: GTK rekeying after FILS authentication Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_auth_gtk_rekey 6.16846 2020-09-05 17:48:02.139194 passed all 1 test case(s) 2020-09-05 17:48:02 ./run-tests.py fils_auth_ptk_rekey_ap DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_auth_ptk_rekey_ap 1/1 Test: PTK rekeying after FILS authentication triggered by AP Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_auth_ptk_rekey_ap 3.287504 2020-09-05 17:48:05.551900 passed all 1 test case(s) 2020-09-05 17:48:05 ./run-tests.py fils_auth_ptk_rekey_ap_erp DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_auth_ptk_rekey_ap_erp 1/1 Test: PTK rekeying after FILS authentication triggered by AP (ERP) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_auth_ptk_rekey_ap_erp 3.279754 2020-09-05 17:48:08.961814 passed all 1 test case(s) 2020-09-05 17:48:08 ./run-tests.py fils_auth_ptk_rekey_ap_ext_key_id DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_auth_ptk_rekey_ap_ext_key_id 1/1 Test: PTK rekeying after FILS authentication triggered by AP (Ext Key ID) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_auth_ptk_rekey_ap_ext_key_id 3.292189 2020-09-05 17:48:12.382363 passed all 1 test case(s) 2020-09-05 17:48:12 ./run-tests.py fils_sk_auth_mismatch DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_auth_mismatch 1/1 Test: FILS SK authentication type mismatch (PFS not supported) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_auth_mismatch 0.260858 2020-09-05 17:48:12.773062 passed all 1 test case(s) 2020-09-05 17:48:12 ./run-tests.py fils_sk_erp DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_erp 1/1 Test: FILS SK using ERP Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_erp 0.256124 2020-09-05 17:48:13.156924 passed all 1 test case(s) 2020-09-05 17:48:13 ./run-tests.py fils_sk_erp_and_reauth DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_erp_and_reauth 1/1 Test: FILS SK using ERP and AP going away Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_erp_and_reauth 1.163553 2020-09-05 17:48:14.446544 passed all 1 test case(s) 2020-09-05 17:48:14 ./run-tests.py fils_sk_erp_another_ssid DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_erp_another_ssid 1/1 Test: FILS SK using ERP and roam to another SSID Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_erp_another_ssid 0.427981 2020-09-05 17:48:15.001464 passed all 1 test case(s) 2020-09-05 17:48:15 ./run-tests.py fils_sk_erp_followed_by_pmksa_caching DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_erp_followed_by_pmksa_caching 1/1 Test: FILS SK ERP following by PMKSA caching Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_erp_followed_by_pmksa_caching 0.335439 2020-09-05 17:48:15.463863 passed all 1 test case(s) 2020-09-05 17:48:15 ./run-tests.py fils_sk_erp_radius_ext DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_erp_radius_ext 1/1 Test: FILS SK using ERP and external RADIUS server Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_erp_radius_ext 0.262808 2020-09-05 17:48:15.852856 passed all 1 test case(s) 2020-09-05 17:48:15 ./run-tests.py fils_sk_erp_radius_roam DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_erp_radius_roam 1/1 Test: FILS SK/ERP and roaming with different AKM Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP Starting AP wlan4 (old add_ap argument type) PASS fils_sk_erp_radius_roam 0.358209 2020-09-05 17:48:16.339134 passed all 1 test case(s) 2020-09-05 17:48:16 ./run-tests.py fils_sk_erp_roam_diff_akm DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_erp_roam_diff_akm 1/1 Test: FILS SK using ERP and SHA256/SHA384 change in roam Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP Starting AP wlan4 (old add_ap argument type) PASS fils_sk_erp_roam_diff_akm 0.394077 2020-09-05 17:48:16.860399 passed all 1 test case(s) 2020-09-05 17:48:16 ./run-tests.py fils_sk_erp_server_flush DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_erp_server_flush 1/1 Test: FILS SK ERP and ERP flush on server, but not on peer Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_erp_server_flush 0.444374 2020-09-05 17:48:17.433493 passed all 1 test case(s) 2020-09-05 17:48:17 ./run-tests.py fils_sk_erp_sha384 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_erp_sha384 1/1 Test: FILS SK using ERP and SHA384 Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_erp_sha384 0.247771 2020-09-05 17:48:17.809901 passed all 1 test case(s) 2020-09-05 17:48:17 ./run-tests.py fils_sk_erp_sim DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_erp_sim 1/1 Test: FILS SK using ERP with SIM Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_erp_sim 0.369071 2020-09-05 17:48:18.305696 passed all 1 test case(s) 2020-09-05 17:48:18 ./run-tests.py fils_sk_full_auth DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_full_auth 1/1 Test: FILS SK full authentication Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_full_auth 1.124876 2020-09-05 17:48:19.558044 passed all 1 test case(s) 2020-09-05 17:48:19 ./run-tests.py fils_sk_hlp DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_hlp 1/1 Test: FILS SK HLP (rapid commit server) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_hlp 0.218725 2020-09-05 17:48:19.903387 passed all 1 test case(s) 2020-09-05 17:48:19 ./run-tests.py fils_sk_hlp_dhcp_parsing DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_hlp_dhcp_parsing 1/1 Test: FILS SK HLP and DHCP response parsing Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_hlp_dhcp_parsing 0.61193 2020-09-05 17:48:20.642812 passed all 1 test case(s) 2020-09-05 17:48:20 ./run-tests.py fils_sk_hlp_no_rapid_commit DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_hlp_no_rapid_commit 1/1 Test: FILS SK HLP (no rapid commit server) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_hlp_no_rapid_commit 0.228353 2020-09-05 17:48:20.998537 passed all 1 test case(s) 2020-09-05 17:48:21 ./run-tests.py fils_sk_hlp_oom DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_hlp_oom 1/1 Test: FILS SK HLP and hostapd OOM Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_hlp_oom 1.450655 2020-09-05 17:48:22.576104 passed all 1 test case(s) 2020-09-05 17:48:22 ./run-tests.py fils_sk_hlp_req_parsing DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_hlp_req_parsing 1/1 Test: FILS SK HLP request parsing Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_hlp_req_parsing 0.421439 2020-09-05 17:48:23.126486 passed all 1 test case(s) 2020-09-05 17:48:23 ./run-tests.py fils_sk_hlp_timeout DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_hlp_timeout 1/1 Test: FILS SK HLP (rapid commit server timeout) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_hlp_timeout 0.249688 2020-09-05 17:48:23.504563 passed all 1 test case(s) 2020-09-05 17:48:23 ./run-tests.py fils_sk_multiple_realms DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_multiple_realms 1/1 Test: FILS SK and multiple realms Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_multiple_realms 0.251568 2020-09-05 17:48:23.883712 passed all 1 test case(s) 2020-09-05 17:48:23 ./run-tests.py fils_sk_pfs_19 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pfs_19 1/1 Test: FILS SK with PFS (DH group 19) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pfs_19 0.258762 2020-09-05 17:48:24.266595 passed all 1 test case(s) 2020-09-05 17:48:24 ./run-tests.py fils_sk_pfs_20 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pfs_20 1/1 Test: FILS SK with PFS (DH group 20) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pfs_20 0.260736 2020-09-05 17:48:24.652748 passed all 1 test case(s) 2020-09-05 17:48:24 ./run-tests.py fils_sk_pfs_21 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pfs_21 1/1 Test: FILS SK with PFS (DH group 21) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pfs_21 0.252189 2020-09-05 17:48:25.035392 passed all 1 test case(s) 2020-09-05 17:48:25 ./run-tests.py fils_sk_pfs_25 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pfs_25 1/1 Test: FILS SK with PFS (DH group 25) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pfs_25 0.254092 2020-09-05 17:48:25.416341 passed all 1 test case(s) 2020-09-05 17:48:25 ./run-tests.py fils_sk_pfs_26 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pfs_26 1/1 Test: FILS SK with PFS (DH group 26) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pfs_26 0.254236 2020-09-05 17:48:25.799252 passed all 1 test case(s) 2020-09-05 17:48:25 ./run-tests.py fils_sk_pfs_27 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pfs_27 1/1 Test: FILS SK with PFS (DH group 27) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pfs_27 0.257445 2020-09-05 17:48:26.183904 passed all 1 test case(s) 2020-09-05 17:48:26 ./run-tests.py fils_sk_pfs_28 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pfs_28 1/1 Test: FILS SK with PFS (DH group 28) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pfs_28 0.251965 2020-09-05 17:48:26.562035 passed all 1 test case(s) 2020-09-05 17:48:26 ./run-tests.py fils_sk_pfs_29 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pfs_29 1/1 Test: FILS SK with PFS (DH group 29) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pfs_29 0.253943 2020-09-05 17:48:26.942733 passed all 1 test case(s) 2020-09-05 17:48:26 ./run-tests.py fils_sk_pfs_30 DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pfs_30 1/1 Test: FILS SK with PFS (DH group 30) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pfs_30 0.261886 2020-09-05 17:48:27.330703 passed all 1 test case(s) 2020-09-05 17:48:27 ./run-tests.py fils_sk_pfs_group_mismatch DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pfs_group_mismatch 1/1 Test: FILS SK PFS DH group mismatch Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pfs_group_mismatch 0.191941 2020-09-05 17:48:27.650144 passed all 1 test case(s) 2020-09-05 17:48:27 ./run-tests.py fils_sk_pfs_pmksa_caching DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pfs_pmksa_caching 1/1 Test: FILS SK with PFS and PMKSA caching Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pfs_pmksa_caching 0.557531 2020-09-05 17:48:28.335665 passed all 1 test case(s) 2020-09-05 17:48:28 ./run-tests.py fils_sk_pmksa_caching DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pmksa_caching 1/1 Test: FILS SK and PMKSA caching Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pmksa_caching 0.394334 2020-09-05 17:48:28.859057 passed all 1 test case(s) 2020-09-05 17:48:28 ./run-tests.py fils_sk_pmksa_caching_and_cache_id DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pmksa_caching_and_cache_id 1/1 Test: FILS SK and PMKSA caching with Cache Identifier Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP Starting AP wlan4 (old add_ap argument type) PASS fils_sk_pmksa_caching_and_cache_id 0.34933 2020-09-05 17:48:29.335304 passed all 1 test case(s) 2020-09-05 17:48:29 ./run-tests.py fils_sk_pmksa_caching_ctrl_ext DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pmksa_caching_ctrl_ext 1/1 Test: FILS SK and PMKSA caching with Cache Identifier and external management Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PMKSA_GET: 02:00:00:00:03:00 f3e97ce314bfdaab7b2a99ae670d3ba3 fe28a08b9c5d452324b7f709304c3db610184f87c7f639d240e22205c6a91c6c731af57f83857567bce7cfbfbf43f247 30240 43200 524288 0 ffee Starting AP wlan4 (old add_ap argument type) PASS fils_sk_pmksa_caching_ctrl_ext 0.349991 2020-09-05 17:48:29.811728 passed all 1 test case(s) 2020-09-05 17:48:29 ./run-tests.py fils_sk_pmksa_caching_ocv DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_pmksa_caching_ocv 1/1 Test: FILS SK and PMKSA caching with OCV Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_pmksa_caching_ocv 0.400128 2020-09-05 17:48:30.339289 passed all 1 test case(s) 2020-09-05 17:48:30 ./run-tests.py fils_sk_sha384_full_auth DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fils_sk_sha384_full_auth 1/1 Test: FILS SK full authentication (SHA384) Starting AP as-erp Starting AP wlan3 (old add_ap argument type) Connect STA wlan0 to AP PASS fils_sk_sha384_full_auth 1.126309 2020-09-05 17:48:31.592695 passed all 1 test case(s) 2020-09-05 17:48:31 ./run-tests.py fst_ack_response_proto DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ack_response_proto 1/1 Test: FST protocol testing for Ack Response Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP PASS fst_ack_response_proto 0.50124 2020-09-05 17:48:32.222509 passed all 1 test case(s) 2020-09-05 17:48:32 ./run-tests.py fst_ap_ack_request_with_bad_fsts_id DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_ack_request_with_bad_fsts_id 1/1 Test: FST AP ack request with bad fsts id Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad scenario was handled correctly (Ack request received with bad fsts_id) PASS fst_ap_ack_request_with_bad_fsts_id 5.466184 2020-09-05 17:48:37.816126 passed all 1 test case(s) 2020-09-05 17:48:37 ./run-tests.py fst_ap_ack_request_with_no_session DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_ack_request_with_no_session 1/1 Test: FST AP ack request with no session Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Frame was ignored (Ack request) PASS fst_ap_ack_request_with_no_session 5.464473 2020-09-05 17:48:43.407063 passed all 1 test case(s) 2020-09-05 17:48:43 ./run-tests.py fst_ap_ack_request_with_session_not_established_init_side DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_ack_request_with_session_not_established_init_side 1/1 Test: FST AP ack request with session not established init side Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad scenario was handled correctly (Ack request received on the initiator side before session was established) PASS fst_ap_ack_request_with_session_not_established_init_side 5.994046 2020-09-05 17:48:49.526960 passed all 1 test case(s) 2020-09-05 17:48:49 ./run-tests.py fst_ap_ack_request_with_session_not_established_resp_side DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_ack_request_with_session_not_established_resp_side 1/1 Test: FST AP ack request with session not established resp side Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad scenario was handled correctly (Ack request received on the responder side before session was established) PASS fst_ap_ack_request_with_session_not_established_resp_side 5.995709 2020-09-05 17:48:55.649141 passed all 1 test case(s) 2020-09-05 17:48:55 ./run-tests.py fst_ap_ack_request_with_session_not_set_up DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_ack_request_with_session_not_set_up 1/1 Test: FST AP ack request with session not set up Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad scenario was handled correctly (Ack request received before the session was set up) PASS fst_ap_ack_request_with_session_not_set_up 5.469281 2020-09-05 17:49:01.246949 passed all 1 test case(s) 2020-09-05 17:49:01 ./run-tests.py fst_ap_ack_response_with_no_ack_request DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_ack_response_with_no_ack_request 1/1 Test: FST AP ack response with no ack request Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad scenario was handled correctly (Ack response received before ack request was sent) PASS fst_ap_ack_response_with_no_ack_request 5.476693 2020-09-05 17:49:06.850654 passed all 1 test case(s) 2020-09-05 17:49:06 ./run-tests.py fst_ap_ack_response_with_no_session DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_ack_response_with_no_session 1/1 Test: FST AP ack response with no session Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Frame was ignored (Ack response) PASS fst_ap_ack_response_with_no_session 5.475787 2020-09-05 17:49:12.450784 passed all 1 test case(s) 2020-09-05 17:49:12 ./run-tests.py fst_ap_ack_response_with_session_not_established_init_side DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_ack_response_with_session_not_established_init_side 1/1 Test: FST AP ack response with session not established init side Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad scenario was handled correctly (Ack response received on the initiator side before session was established) PASS fst_ap_ack_response_with_session_not_established_init_side 5.996441 2020-09-05 17:49:18.574917 passed all 1 test case(s) 2020-09-05 17:49:18 ./run-tests.py fst_ap_ack_response_with_session_not_established_resp_side DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_ack_response_with_session_not_established_resp_side 1/1 Test: FST AP ack response with session not established resp side Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad scenario was handled correctly (Ack response received on the responder side before session was established) PASS fst_ap_ack_response_with_session_not_established_resp_side 5.999277 2020-09-05 17:49:24.701151 passed all 1 test case(s) 2020-09-05 17:49:24 ./run-tests.py fst_ap_ack_response_with_session_not_set_up DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_ack_response_with_session_not_set_up 1/1 Test: FST AP ack response with session not set up Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad scenario was handled correctly (Ack response received before the session was set up) PASS fst_ap_ack_response_with_session_not_set_up 5.473749 2020-09-05 17:49:30.299973 passed all 1 test case(s) 2020-09-05 17:49:30 ./run-tests.py fst_ap_config_default DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_config_default 1/1 Test: FST AP configuration default parameters kill_pid: pidfile logs/current/myhostapd.pid does not exist - try again after a second kill_pid: pidfile logs/current/myhostapd.pid does not exist - could not kill the process hostapd didn't start with valid config parameters Traceback (most recent call last): File "./run-tests.py", line 531, in main t(dev, apdev, params) File "/lkp/benchmarks/hwsim/tests/hwsim/test_fst_config.py", line 406, in test_fst_ap_config_default raise Exception("hostapd didn't start with valid config parameters") Exception: hostapd didn't start with valid config parameters FAIL fst_ap_config_default 1.201026 2020-09-05 17:49:31.626224 passed 0 test case(s) skipped 0 test case(s) failed tests: fst_ap_config_default 2020-09-05 17:49:31 ./run-tests.py fst_ap_config_good DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_config_good 1/1 Test: FST AP configuration good parameters kill_pid: pidfile logs/current/myhostapd.pid does not exist - try again after a second kill_pid: pidfile logs/current/myhostapd.pid does not exist - could not kill the process hostapd didn't start with valid config parameters Traceback (most recent call last): File "./run-tests.py", line 531, in main t(dev, apdev, params) File "/lkp/benchmarks/hwsim/tests/hwsim/test_fst_config.py", line 400, in test_fst_ap_config_good raise Exception("hostapd didn't start with valid config parameters") Exception: hostapd didn't start with valid config parameters FAIL fst_ap_config_good 1.214574 2020-09-05 17:49:32.968016 passed 0 test case(s) skipped 0 test case(s) failed tests: fst_ap_config_good 2020-09-05 17:49:32 ./run-tests.py fst_ap_config_group_len DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_config_group_len 1/1 Test: FST AP configuration Group max length kill_pid: pidfile logs/current/myhostapd.pid does not exist - try again after a second kill_pid: pidfile logs/current/myhostapd.pid does not exist - could not kill the process PASS fst_ap_config_group_len 1.038532 2020-09-05 17:49:34.143336 passed all 1 test case(s) 2020-09-05 17:49:34 ./run-tests.py fst_ap_config_llt_nan DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_config_llt_nan 1/1 Test: FST AP configuration LLT is not a number kill_pid: pidfile logs/current/myhostapd.pid does not exist - try again after a second kill_pid: pidfile logs/current/myhostapd.pid does not exist - could not kill the process PASS fst_ap_config_llt_nan 1.037366 2020-09-05 17:49:35.306875 passed all 1 test case(s) 2020-09-05 17:49:35 ./run-tests.py fst_ap_config_llt_neg DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_config_llt_neg 1/1 Test: FST AP configuration negative LLT kill_pid: pidfile logs/current/myhostapd.pid does not exist - try again after a second kill_pid: pidfile logs/current/myhostapd.pid does not exist - could not kill the process PASS fst_ap_config_llt_neg 1.039218 2020-09-05 17:49:36.472550 passed all 1 test case(s) 2020-09-05 17:49:36 ./run-tests.py fst_ap_config_llt_too_big DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_config_llt_too_big 1/1 Test: FST AP configuration LLT is too big kill_pid: pidfile logs/current/myhostapd.pid does not exist - try again after a second kill_pid: pidfile logs/current/myhostapd.pid does not exist - could not kill the process PASS fst_ap_config_llt_too_big 1.036907 2020-09-05 17:49:37.637726 passed all 1 test case(s) 2020-09-05 17:49:37 ./run-tests.py fst_ap_config_llt_zero DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_config_llt_zero 1/1 Test: FST AP configuration zero LLT kill_pid: pidfile logs/current/myhostapd.pid does not exist - try again after a second kill_pid: pidfile logs/current/myhostapd.pid does not exist - could not kill the process PASS fst_ap_config_llt_zero 1.038161 2020-09-05 17:49:38.802893 passed all 1 test case(s) 2020-09-05 17:49:38 ./run-tests.py fst_ap_config_oom DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_config_oom 1/1 Test: FST AP configuration and OOM Starting AP wlan3 (old add_ap argument type) PASS fst_ap_config_oom 0.103994 2020-09-05 17:49:39.033615 passed all 1 test case(s) 2020-09-05 17:49:39 ./run-tests.py fst_ap_config_pri_large DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_config_pri_large 1/1 Test: FST AP configuration Priority too large kill_pid: pidfile logs/current/myhostapd.pid does not exist - try again after a second kill_pid: pidfile logs/current/myhostapd.pid does not exist - could not kill the process PASS fst_ap_config_pri_large 1.038533 2020-09-05 17:49:40.196485 passed all 1 test case(s) 2020-09-05 17:49:40 ./run-tests.py fst_ap_config_pri_nan DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_config_pri_nan 1/1 Test: FST AP configuration Priority not a number kill_pid: pidfile logs/current/myhostapd.pid does not exist - try again after a second kill_pid: pidfile logs/current/myhostapd.pid does not exist - could not kill the process PASS fst_ap_config_pri_nan 1.03777 2020-09-05 17:49:41.359994 passed all 1 test case(s) 2020-09-05 17:49:41 ./run-tests.py fst_ap_config_pri_neg DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_config_pri_neg 1/1 Test: FST AP configuration Priority negative kill_pid: pidfile logs/current/myhostapd.pid does not exist - try again after a second kill_pid: pidfile logs/current/myhostapd.pid does not exist - could not kill the process PASS fst_ap_config_pri_neg 1.037882 2020-09-05 17:49:42.523844 passed all 1 test case(s) 2020-09-05 17:49:42 ./run-tests.py fst_ap_config_pri_zero DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_config_pri_zero 1/1 Test: FST AP configuration Priority zero kill_pid: pidfile logs/current/myhostapd.pid does not exist - try again after a second kill_pid: pidfile logs/current/myhostapd.pid does not exist - could not kill the process PASS fst_ap_config_pri_zero 1.037601 2020-09-05 17:49:43.687593 passed all 1 test case(s) 2020-09-05 17:49:43 ./run-tests.py fst_ap_connect_to_fst_sta DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_connect_to_fst_sta 1/1 Test: FST AP connecting to FST STA Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP PASS fst_ap_connect_to_fst_sta 2.391988 2020-09-05 17:49:46.205204 passed all 1 test case(s) 2020-09-05 17:49:46 ./run-tests.py fst_ap_connect_to_non_fst_sta DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_connect_to_non_fst_sta 1/1 Test: FST AP connecting to non-FST STA Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan0 to AP PASS fst_ap_connect_to_non_fst_sta 2.383183 2020-09-05 17:49:48.716235 passed all 1 test case(s) 2020-09-05 17:49:48 ./run-tests.py fst_ap_ctrl_iface DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_ctrl_iface 1/1 Test: FST control interface behavior Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Initial session params: {'old_peer_addr': '00:00:00:00:00:00', 'new_peer_addr': '00:00:00:00:00:00', 'new_ifname': 'NONE', 'old_ifname': 'NONE', 'llt': '50', 'state': 'INITIAL'} Session params after configuration: {'old_peer_addr': '02:00:00:00:05:00', 'new_peer_addr': '02:00:00:00:05:00', 'new_ifname': 'wlan4', 'old_ifname': 'wlan3', 'llt': '100', 'state': 'INITIAL'} Interface peers: ['02:00:00:00:05:00'] Peer MB IEs: 9e1c0c0200000000000000000000000000000000000000ff0200000006009e1c0c0400000000000000000000000000000000000000ff020000000600 Interfaces: [{'name': 'wlan4|02', 'priority': '00', 'llt': '00'}, {'name': 'wlan3|02', 'priority': '00', 'llt': '00'}] Groups: ['fstg0'] PASS fst_ap_ctrl_iface 0.493596 2020-09-05 17:49:49.334749 passed all 1 test case(s) 2020-09-05 17:49:49 ./run-tests.py fst_ap_initiate_session DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_initiate_session 1/1 Test: FST AP initiate session Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Session initiated PASS fst_ap_initiate_session 0.471228 2020-09-05 17:49:49.934006 passed all 1 test case(s) 2020-09-05 17:49:49 ./run-tests.py fst_ap_initiate_session_bad_peer_addr DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_initiate_session_bad_peer_addr 1/1 Test: FST AP initiate session - bad peer address Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad parameter was detected (Peer address) PASS fst_ap_initiate_session_bad_peer_addr 0.464581 2020-09-05 17:49:50.523738 passed all 1 test case(s) 2020-09-05 17:49:50 ./run-tests.py fst_ap_initiate_session_concurrent_setup_request DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_initiate_session_concurrent_setup_request 1/1 Test: FST AP initiate session - concurrent setup request Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad parameter was detected (Concurrent setup request) PASS fst_ap_initiate_session_concurrent_setup_request 0.471483 2020-09-05 17:49:51.122013 passed all 1 test case(s) 2020-09-05 17:49:51 ./run-tests.py fst_ap_initiate_session_invalid_session_id DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_initiate_session_invalid_session_id 1/1 Test: FST AP initiate session - invalid session id Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad parameter was detected (Session ID) PASS fst_ap_initiate_session_invalid_session_id 0.464742 2020-09-05 17:49:51.713557 passed all 1 test case(s) 2020-09-05 17:49:51 ./run-tests.py fst_ap_initiate_session_no_new_iface DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_initiate_session_no_new_iface 1/1 Test: FST AP initiate session - no new iface Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad parameter was detected (No new_iface was set) PASS fst_ap_initiate_session_no_new_iface 0.470638 2020-09-05 17:49:52.310531 passed all 1 test case(s) 2020-09-05 17:49:52 ./run-tests.py fst_ap_initiate_session_no_params DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_initiate_session_no_params 1/1 Test: FST AP initiate session - no params Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad parameter was detected (No params passed to session initiate) PASS fst_ap_initiate_session_no_params 0.46722 2020-09-05 17:49:52.905798 passed all 1 test case(s) 2020-09-05 17:49:52 ./run-tests.py fst_ap_initiate_session_request_with_bad_stie DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_initiate_session_request_with_bad_stie 1/1 Test: FST AP initiate session - request with bad stie Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad parameter was detected (Request with bad st ie) PASS fst_ap_initiate_session_request_with_bad_stie 5.463311 2020-09-05 17:49:58.495852 passed all 1 test case(s) 2020-09-05 17:49:58 ./run-tests.py fst_ap_initiate_session_response_with_bad_stie DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_initiate_session_response_with_bad_stie 1/1 Test: FST AP initiate session - response with bad stie Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad parameter was detected (Response with bad st ie) PASS fst_ap_initiate_session_response_with_bad_stie 0.454826 2020-09-05 17:49:59.076748 passed all 1 test case(s) 2020-09-05 17:49:59 ./run-tests.py fst_ap_initiate_session_response_with_reject DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_initiate_session_response_with_reject 1/1 Test: FST AP initiate session - response with reject Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad parameter was detected (Response with reject) PASS fst_ap_initiate_session_response_with_reject 0.468755 2020-09-05 17:49:59.672934 passed all 1 test case(s) 2020-09-05 17:49:59 ./run-tests.py fst_ap_initiate_session_response_with_zero_llt DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_initiate_session_response_with_zero_llt 1/1 Test: FST AP initiate session - zero llt Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad parameter was detected (Response with zero llt) PASS fst_ap_initiate_session_response_with_zero_llt 0.476592 2020-09-05 17:50:00.276840 passed all 1 test case(s) 2020-09-05 17:50:00 ./run-tests.py fst_ap_initiate_session_stt_no_response DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_initiate_session_stt_no_response 1/1 Test: FST AP initiate session - stt no response Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Bad parameter was detected (No response, STT) PASS fst_ap_initiate_session_stt_no_response 0.98285 2020-09-05 17:50:01.386474 passed all 1 test case(s) 2020-09-05 17:50:01 ./run-tests.py fst_ap_remove_session_bad_session_id DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_remove_session_bad_session_id 1/1 Test: FST AP remove session - bad session id Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Remove scenario ended as expected (Bad session id) PASS fst_ap_remove_session_bad_session_id 0.461013 2020-09-05 17:50:01.973635 passed all 1 test case(s) 2020-09-05 17:50:01 ./run-tests.py fst_ap_remove_session_established DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_remove_session_established 1/1 Test: FST AP remove session - established Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Session removed PASS fst_ap_remove_session_established 0.46757 2020-09-05 17:50:02.566511 passed all 1 test case(s) 2020-09-05 17:50:02 ./run-tests.py fst_ap_remove_session_no_params DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_remove_session_no_params 1/1 Test: FST AP remove session - no params Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Remove scenario ended as expected (No params) PASS fst_ap_remove_session_no_params 0.463076 2020-09-05 17:50:03.154787 passed all 1 test case(s) 2020-09-05 17:50:03 ./run-tests.py fst_ap_remove_session_not_established DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_remove_session_not_established 1/1 Test: FST AP remove session - not established Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Remove scenario ended as expected (Remove non-established session) PASS fst_ap_remove_session_not_established 5.466371 2020-09-05 17:50:08.746932 passed all 1 test case(s) 2020-09-05 17:50:08 ./run-tests.py fst_ap_session_request_with_no_session DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_session_request_with_no_session 1/1 Test: FST AP session request with no session Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Frame received, session created PASS fst_ap_session_request_with_no_session 0.46252 2020-09-05 17:50:09.334966 passed all 1 test case(s) 2020-09-05 17:50:09 ./run-tests.py fst_ap_session_response_accept_with_no_session DEV: wlan0: 02:00:00:00:00:00 DEV: wlan1: 02:00:00:00:01:00 DEV: wlan2: 02:00:00:00:02:00 APDEV: wlan3 APDEV: wlan4 START fst_ap_session_response_accept_with_no_session 1/1 Test: FST AP session response accept with no session Starting AP wlan3 (old add_ap argument type) Starting AP wlan4 (old add_ap argument type) Connect STA wlan5 to AP Connect STA wlan6 to AP Success. Frame was ignored (Session Response) PASS fst_ap_session_response_accept_with_no_session 5.463639 2020-09-05 17:50:14.924646 passed all 1 test case(s) 2020-09-05 17:50:14 ./stop.sh 2020-09-05 17:50:16 ./start.sh channels=4 2020-09-05 17:50:16 ./stop.sh