WLAN connecting problem

janopalo · 7 October 2021 10:12

REPRODUCIBILITY (% or how often): 100
BUILD ID = OS VERSION (Settings > About product): 4.2.0.21
HARDWARE (XA2, X10, X10 II, …): XA2 Plus
UI LANGUAGE: slovak
REGRESSION: (compared to previous public release: Yes, No, ?): yes

DESCRIPTION:

Connecting to WLAN, more precisely to internet, is unsuccessful after first time (after first launch of WLAN connection). It seems the phone connect to WLAN, but no internet connection. It must be turned off and turned on again. Then you are connected to internet.

PRECONDITIONS:

Previous version no such problem.

STEPS TO REPRODUCE:

WLAN is turned on.
The phone connect to the WLAN.
The internet connection doesn’t work.
The WLAN is turned off.
The WLAN is turned on.
The internet connection work.

EXPECTED RESULT:

After turning on the WLAN first time the internet connection works.

ACTUAL RESULT:

The internet connection works after second turning on of WLAN.

ADDITIONAL INFORMATION:

(Please ALWAYS attach relevant data such as logs, screenshots, etc…)

karry · 7 October 2021 20:49

Hi. It is reproducible? It is happening just on one network on all? What network configuration looks like when you connect first time? Does wlan0 interface have IP address? How routing table looks like? Is gateway reachable? Is DNS server reachable?

I believe that such bug may be annoying, but it is not really common. Some additional information would be useful to understand in which component is the problem.

gabs5807 · 8 October 2021 02:10

Same problem here. It seems that connection to the latest wlan did not work every time.
When i leave home for a short time (no connection via mobile connection nor to an other wlan) the reconnect did not work sometimes (i have to turn wlan of and on again, as described above).
When i connect to an other wlan in between, reconnect to my home wlan always works fine.
So it also can be a problem with the disconnection (?) .

Edit: HW is Tablet (jolla brand) and Fairphone2 (community port).

donni106 · 8 October 2021 12:34

same here with XA2 and latest 4.2 release. i often have to restart android support to get wifi back to work for android apps.

karry · 11 October 2021 13:02

It seems to be reproducible with some networks. For me it is happening on corporate network with WPA2-PSK authentication. When I connect to the network, everything works fine. When I go outside for lunch and return to the office, network seems to be connected to the wifi, but gateway is not respoding for ping and even arp queries. It seems that it is happening even when I am going to to different floor and connecting to different access points.

when everything works, network configuration looks like this:

[root@Xperia ~]# ip -4 addr show wlan0
19: wlan0: <BROADCAST,MULTICAST,DYNAMIC,UP,LOWER_UP> mtu 1500 qdisc mq state UP qlen 3000
    inet 10.180.4.213/21 brd 10.180.7.255 scope global wlan0
       valid_lft forever preferred_lft forever

[root@Xperia ~]# ip -4 route list
default via 10.180.0.1 dev wlan0 
8.8.8.8 via 10.180.0.1 dev wlan0 
10.180.0.0/21 dev wlan0  proto kernel  scope link  src 10.180.4.213 
10.180.0.1 dev wlan0  scope link 
192.168.2.0/24 dev rndis0  proto kernel  scope link  src 192.168.2.15 

[root@Xperia ~]# ip -4 neigh list
10.180.0.1 dev wlan0 lladdr 00:1c:7f:00:50:f0 REACHABLE
192.168.2.10 dev rndis0 lladdr ca:b2:44:f0:fa:f1 REACHABLE
10.180.0.3 dev wlan0 lladdr b8:59:9f:49:1b:f4 STALE


[root@Xperia ~]# ping -c 1 8.8.8.8
PING 8.8.8.8 (8.8.8.8): 56 data bytes
64 bytes from 8.8.8.8: seq=0 ttl=120 time=61.961 ms

--- 8.8.8.8 ping statistics ---
1 packets transmitted, 1 packets received, 0% packet loss
round-trip min/avg/max = 61.961/61.961/61.961 ms


[root@Xperia ~]# ip -6 addr show wlan0
19: wlan0: <BROADCAST,MULTICAST,DYNAMIC,UP,LOWER_UP> mtu 1500 state UP qlen 3000
    inet6 fe80::3e01:efff:fea2:bd0b/64 scope link 
       valid_lft forever preferred_lft forever

[root@Xperia ~]# ip -6 route list
fe80::/64 dev rndis0  proto kernel  metric 256 
fe80::/64 dev wlan0  proto kernel  metric 256 

[root@Xperia ~]# ip -6 neigh list


[root@Xperia ~]# ping -6 google.com
PING google.com (2a00:1450:4014:80c::200e): 56 data bytes
ping: sendto: Network is unreachable

when this problem happens, configutation is the same, but gateway is not responding even for ARP resolutions…

[root@Xperia ~]# ping -c 1 8.8.8.8
PING 8.8.8.8 (8.8.8.8): 56 data bytes

--- 8.8.8.8 ping statistics ---
1 packets transmitted, 0 packets received, 100% packet loss

[root@Xperia ~]# ip -4 neigh list
192.168.2.12 dev rndis0 lladdr 06:ae:4b:b0:7a:5f REACHABLE
10.180.0.1 dev wlan0  FAILED
10.180.0.3 dev wlan0 lladdr b8:59:9f:49:1b:f4 STALE

here is pcap with dhcp, icmp, arp and 801.1x packets: xperia-wlan0.pcap captured by this command:

 tcpdump -w /home/defaultuser/wlan0-xperia1.pcap -Z defaultuser -i wlan0 --immediate-mode  -vv 'udp port 67 or udp port 68 or icmp or arp or ether proto 0x888e'

It seems that gateway stops responding after recent 802.1x exchange… Here is log from wpa_supplicant around problematic time:

říj 11 14:05:35 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=US
říj 11 14:05:55 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=US
říj 11 14:13:22 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=US
říj 11 14:13:32 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=US
říj 11 14:20:37 Xperia wpa_supplicant[5223]: wlan0: WPA: Group rekeying completed with f4:bd:9e:9c:6e:0c [GTK=CCMP]
říj 11 14:21:52 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:bd:9e:9c:6e:0c reason=0 locally_generated=1
říj 11 14:21:52 Xperia wpa_supplicant[5223]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/4
říj 11 14:21:57 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:22:07 Xperia wpa_supplicant[5223]: wlan0: Authentication with f4:bd:9e:9c:47:8c timed out.
říj 11 14:22:07 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:bd:9e:9c:47:8c reason=3 locally_generated=1
říj 11 14:22:08 Xperia wpa_supplicant[5223]: wlan0: Reject scan trigger since one is already pending
říj 11 14:22:08 Xperia wpa_supplicant[5223]: wlan0: Failed to initiate AP scan
říj 11 14:22:08 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:22:18 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=f4:bd:9e:9c:82:c3 status_code=1 timeout=scan
říj 11 14:22:31 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:22:41 Xperia wpa_supplicant[5223]: wlan0: Authentication with f4:bd:9e:9c:4f:03 timed out.
říj 11 14:22:41 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:bd:9e:9c:4f:03 reason=3 locally_generated=1
říj 11 14:22:41 Xperia wpa_supplicant[5223]: wlan0: Reject scan trigger since one is already pending
říj 11 14:22:41 Xperia wpa_supplicant[5223]: wlan0: Failed to initiate AP scan
říj 11 14:22:49 Xperia wpa_supplicant[5223]: wlan0: Reject scan trigger since one is already pending
říj 11 14:22:49 Xperia wpa_supplicant[5223]: wlan0: Failed to initiate AP scan
říj 11 14:22:49 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=US
říj 11 14:22:49 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:22:54 Xperia wpa_supplicant[5223]: wlan0: Associated with f4:bd:9e:9d:d4:e3
říj 11 14:22:54 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
říj 11 14:22:54 Xperia wpa_supplicant[5223]: wlan0: WPA: Key negotiation completed with f4:bd:9e:9d:d4:e3 [PTK=CCMP GTK=CCMP]
říj 11 14:22:54 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-CONNECTED - Connection to f4:bd:9e:9d:d4:e3 completed [id=0 id_str=]
říj 11 14:23:01 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:bd:9e:9d:d4:e3 reason=0 locally_generated=1
říj 11 14:23:01 Xperia wpa_supplicant[5223]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/4
říj 11 14:23:02 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:23:11 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=f4:bd:9e:9c:b9:63 status_code=1 timeout=assoc
říj 11 14:23:12 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:23:22 Xperia wpa_supplicant[5223]: wlan0: Authentication with f4:bd:9e:9c:b9:6c timed out.
říj 11 14:23:22 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:bd:9e:9c:b9:6c reason=3 locally_generated=1
říj 11 14:23:26 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:23:35 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=f4:bd:9e:9c:b5:c3 status_code=1 timeout=assoc
říj 11 14:23:55 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:24:04 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=f4:bd:9e:9d:e1:4c status_code=1 timeout=assoc
říj 11 14:24:25 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:24:25 Xperia wpa_supplicant[5223]: wlan0: Associated with f4:bd:9e:9f:34:e3
říj 11 14:24:25 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
říj 11 14:24:25 Xperia wpa_supplicant[5223]: wlan0: WPA: Key negotiation completed with f4:bd:9e:9f:34:e3 [PTK=CCMP GTK=CCMP]
říj 11 14:24:25 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-CONNECTED - Connection to f4:bd:9e:9f:34:e3 completed [id=0 id_str=]
říj 11 14:24:44 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:bd:9e:9f:34:e3 reason=0 locally_generated=1
říj 11 14:24:44 Xperia wpa_supplicant[5223]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/4
říj 11 14:24:44 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:24:53 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=f4:bd:9e:9c:4f:03 status_code=1 timeout=assoc
říj 11 14:24:53 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:25:03 Xperia wpa_supplicant[5223]: wlan0: Authentication with f4:bd:9e:9f:34:ec timed out.
říj 11 14:25:03 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f4:bd:9e:9f:34:ec reason=3 locally_generated=1
říj 11 14:25:08 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:25:18 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=f4:bd:9e:9d:9a:83 status_code=1 timeout=assoc
říj 11 14:25:30 Xperia wpa_supplicant[5223]: wlan0: Trying to associate with SSID 'guest.*******'
říj 11 14:25:39 Xperia wpa_supplicant[5223]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=f4:bd:9e:9d:d4:e3 status_code=1 timeout=scan

I cannot get logs from wifi infrastructure unfortunately

Notebook with Kubuntu 20.04, Network Manager 1.26.2 and wpa_supplicant v2.9 works fine on the same network. I have just a limited knowledge about 802.1x protocols, so I have no idea howto investigate it more deeply…

edit1:

when network is connected, wpa_cli status output:

[root@Xperia ~]# wpa_cli status
Selected interface 'wlan0'
bssid=f4:bd:9e:9c:6e:0c
freq=5240
ssid=guest.******
id=0
mode=station
wifi_generation=5
pairwise_cipher=CCMP
group_cipher=CCMP
key_mgmt=WPA2-PSK
wpa_state=COMPLETED
ip_address=10.180.4.213
address=3c:01:ef:a2:bd:0b
uuid=e4d8c6df-a2ed-5411-8ea1-692167abe033
ieee80211ac=1

when it is not working:

[root@Xperia ~]# wpa_cli status
Selected interface 'wlan0'
wpa_state=ASSOCIATING
ip_address=10.180.4.213
address=3c:01:ef:a2:bd:0b
uuid=e4d8c6df-a2ed-5411-8ea1-692167abe033

I tried wpa_cli reconnect, but it doen’t help. Just systemctl restart connman is fixing this state…