Migrated from https://crbug.com/943157

UserAgent: Mozilla/5.0 (X11; CrOS x86_64 11647.91.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/73.0.3683.79 Safari/537.36
Platform: 11647.91.0 (Official Build) beta-channel eve

Example URL:

Steps to reproduce the problem:
No specific steps, but most often it happens after waking up from sleep.

What is the expected behavior?

What went wrong?
Wifi disconnects/connects several times at random times, often after waking up from sleep. It takes couple of minutes to get stable connection. I've attached logs from my MikroTik RB751U-2HnD router, so you can match timings in logs. No other devices have such issue on the same network.

Did this work before? Yes I'm not sure, but I believe it's 72

Chrome version: 73.0.3683.79 Channel: beta
OS Version: 11647.91.0
Flash Version:

Repeating pattern from dmesg:
[62245.178075] wlan0: authenticate with d4:ca:6d:6a:38:99
[62245.185249] wlan0: send auth to d4:ca:6d:6a:38:99 (try 1/3)
[62245.189472] wlan0: authenticated
[62245.190241] wlan0: associate with d4:ca:6d:6a:38:99 (try 1/3)
[62245.193564] wlan0: RX AssocResp from d4:ca:6d:6a:38:99 (capab=0x431 status=0 aid=1)
[62245.194566] wlan0: associated
[62245.799234] iwlwifi 0000:01:00.0: No beacon heard and the time event is over already...
[62245.799249] wlan0: Connection to AP d4:ca:6d:6a:38:99 lost
[62245.869879] audit: type=1400 audit(1552899361.857:139): avc: denied { sigchld } for pid=1675 comm="shill" scontext=u:r:cros_init_shill:s0 tcontext=u:r:cros_shill:s0 tclass=process permissive=0

Repeating pattern from netlog:
2019-03-18T10:54:07.642662+02:00 NOTICE wpa_supplicant[643]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d4:ca:6d:6a:38:99 reason=4 locally_generated=1
2019-03-18T10:54:07.644146+02:00 ERR wpa_supplicant[643]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
2019-03-18T10:54:07.644176+02:00 ERR wpa_supplicant[643]: nl80211: Failed to set IPv4 unicast in multicast filter
2019-03-18T10:54:07.646034+02:00 INFO shill[1675]: [INFO:wifi.cc(873)] WiFi wlan0 supplicant updated DisconnectReason to -4
2019-03-18T10:54:07.649905+02:00 WARNING shill[1675]: [WARNING:wifi_service.cc(796)] Service 0 will disconnect due to no remaining endpoints.
2019-03-18T10:54:07.657151+02:00 ERR shill[1675]: [ERROR:object_proxy.cc(581)] Failed to call method: fi.w1.wpa_supplicant1.Interface.Disconnect: object_path= /fi/w1/wpa_supplicant1/Interfaces/1: fi.w1.wpa_supplicant1.NotConnected: This interface is not connected
2019-03-18T10:54:07.661589+02:00 ERR shill[1675]: [ERROR:dbus_method_invoker.h(113)] CallMethodAndBlockWithTimeout(...): Domain=dbus, Code=fi.w1.wpa_supplicant1.NotConnected, Message=This interface is not connected
2019-03-18T10:54:07.661618+02:00 ERR shill[1675]: [ERROR:chromeos_supplicant_interface_proxy.cc(184)] Failed to disconnect: fi.w1.wpa_supplicant1.NotConnected This interface is not connected
2019-03-18T10:54:07.664230+02:00 INFO shill[1675]: [INFO:dhcp_config.cc(194)] Stopping 25484 (ReleaseIP)
2019-03-18T10:54:07.675143+02:00 INFO dhcpcd[25484]: received SIGTERM, stopping
2019-03-18T10:54:07.675159+02:00 INFO dhcpcd[25484]: wlan0: removing interface
2019-03-18T10:54:07.680751+02:00 INFO dhcpcd[25484]: status changed to Release
2019-03-18T10:54:07.680878+02:00 INFO dhcpcd[25484]: dhcpcd exited
2019-03-18T10:54:07.700536+02:00 INFO shill[1675]: [INFO:service.cc(379)] Service 0: state Online -> Idle
2019-03-18T10:54:07.700566+02:00 INFO shill[1675]: [INFO:manager.cc(1488)] Service 0 updated; state: Idle failure Unknown
2019-03-18T10:54:07.703142+02:00 INFO shill[1675]: [INFO:manager.cc(1488)] Service 0 updated; state: Idle failure Unknown
2019-03-18T10:54:07.709887+02:00 INFO shill[1675]: [INFO:manager.cc(1633)] Default physical service: 49 (not connected)
2019-03-18T10:54:07.711042+02:00 INFO shill[1675]: [INFO:service.cc(293)] Suppressed autoconnect to service 49 (no endpoints)
2019-03-18T10:54:07.711050+02:00 INFO shill[1675]: [INFO:service.cc(293)] Suppressed autoconnect to service 0 (no endpoints)
2019-03-18T10:54:07.713500+02:00 INFO shill[1675]: [INFO:wifi.cc(1681)] WiFi wlan0 StateChanged completed -> disconnected
2019-03-18T10:54:07.749472+02:00 INFO shill[1675]: [INFO:wifi.cc(1681)] WiFi wlan0 StateChanged disconnected -> inactive
2019-03-18T10:54:16.207846+02:00 INFO shill[1675]: [INFO:wifi.cc(315)] Scan on wlan0 from ScanTimerHandler
2019-03-18T10:54:17.074566+02:00 WARNING shill[1675]: [WARNING:nl80211_message.cc(485)] Unknown/unhandled netlink nl80211 message 0x71
2019-03-18T10:54:17.079385+02:00 INFO shill[1675]: [INFO:service.cc(271)] wifi service 462 constructed.
2019-03-18T10:54:17.079814+02:00 NOTICE wpa_supplicant[643]: Removed BSSID d4:ca:6d:6a:38:99 from blacklist (clear)

Issue 172222923

Mentioned issues (15)

Links (22)

Issue metadata