New issue
Advanced search Search tips
Note: Color blocks (like or ) mean that a user may not be available. Tooltip shows the reason.

Issue 843416 link

Starred by 15 users

Issue metadata

Status: Assigned
Owner:
Last visit > 30 days ago
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 1
Type: Bug-Regression



Sign in to add a comment

Loss of WiFi with "out-of-range" errors in logs

Reported by jim.dantin@chromium.org, May 16 2018

Issue description

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

Example URL:

Steps to reproduce the problem:
1. Open Play Store app that maintains a network connection
2. Change to Chrome in the foreground
3. At some point WiFi connection is lost and "no networks found" is displayed in the status menu. Cannot regain connectivity until WiFi is manually turned off and then back on.

What is the expected behavior?
Both ChromeOS and Android apps should be able to coexist

What went wrong?
Since m67 Android app (PYKL3 Radar) cannot be left running in the background.

WiFi router is an OnHub. Other devices are not affected when this happens.

Did this work before? Yes m66 beta

Chrome version: 67.0.3396.41  Channel: beta
OS Version: 10575.32.0
Flash Version: 29.0.0.171 

Feedback report sent at around 9:30 EDT. Sent to attention Elizabeth Trumbull. Logs attached. from jim.dantin@gmail.com Chromebook Central TC

#CBC-RS/TC-watchlist
 
Components: -Internals>Network Internals>Network>Connectivity OS>Systems>Network
Components: -Internals>Network>Connectivity Platform>ARC
Listnr report with logs mentioned in the description: https://listnr.corp.google.com/report/85449058267

Other reports the mention Wifi issues when using Android Apps:
(1) https://listnr.corp.google.com/report/85462898049

Filed an internal bug for this to check Android App Compatibility: http://b/80317721
Labels: Hotlist-ConOps-CrOS
(Bulk Edit) Adding the new conops Chrome OS hotlist to all open issues with the "#CBC-RS/TC-watchlist" tag, our former tracking tag.
Update:

Note that my WiFi router is an ASUS OnHub SRT-AC1900.

Similar reports from another TC with a different Chromebook model and different router. 

We will both be sending feedback reports tagged:
#WiFi-out-of-range

Currently working with Google WiFi support to see if it is somehow caught in logs. Case ID [5-3619000022887]

The issue has been observed while Android app was running, and when it wasn't. Also noted when on Hangouts.

When outage appeared on Chromebook, my phone (Pixel 2 XL) was not showing problem with WiFi, neither was a Windows laptop.

I have seen the issue on my Acer R11 cyan, as well as the other TC's R11.

Here's the feedback report mentioned in comment #6:
https://listnr.corp.google.com/report/85528716701 (with logs)
Summary: Loss of WiFi with "out-of-range" errors in logs (was: Loss of WiFi while Android app is running in the background)
I would have preferred to open a separate bug on this, but there are posts on the Community Forum with a link to this bug.

Retitling the bug, as the original filer no longer believes that this issue is tied to use of Android apps. Seems like the "out-of-range" errors in logs.

Will pull up other user feedback reports about this issue, but it's challenging to separate out various Wifi-related issues without examining each log file.
This may be a dup of/related to https://crbug.com/667083 (Wifi connection gets dropped intermittently, unable to connect to any networks or recognize any until restart)
From another user on the Forum:
"My Chromebook has a bug where it will suddenly disconnect from the internet. The Wifi symbol will appear to be greyed out and not show any of the many networks in my locale which normally show off. There is no way to reconnect unless you restart the machine. This happens frequently, sometimes two or three times in an hour. I occasionally get a few days off but then it will return. I have Powerwashed the machine to no avail. This happens no matter where I am.

I've noticed on forums that this is a common theme with Chromebooks and it happens on machines made by a wide range of manufacturers so I believe it is software based rather than hardware. In 2012 Google released some sort of statement that they were aware of this bug and working on it and as that was many, many years ago now I'm hoping for a quick fix! It began pretty much exactly as my warranty ran out."

Associated Feedback report: https://listnr.corp.google.com/report/85530168242

Other feedback reports that mention "out of range" error (with logs):
https://listnr.corp.google.com/report/85521462214
https://listnr.corp.google.com/report/85506881488
https://listnr.corp.google.com/report/85506174244
https://listnr.corp.google.com/report/85497307895
https://listnr.corp.google.com/report/85496642376
https://listnr.corp.google.com/report/85491642552
https://listnr.corp.google.com/report/85468020533
Hello all, 

It appears we are getting more reports for this issue on the product forum: 
https://productforums.google.com/forum/#!topic/chromebook-central/OlZIAQI6JMw

Thanks! 
Alisha


Cc: abodenha@chromium.org kaznacheev@chromium.org alberto@chromium.org
Cc: jmuppala@chromium.org briannorris@chromium.org grundler@chromium.org aashuto...@chromium.org harpreet@chromium.org kirtika@chromium.org
aashutoshk@ - please check if you are able to repro this.
Cannot reproduce the issue with OnHub and Netgear router. Device was auron_yuna running (10575.58.0) latest stable image.
The device was connected to channel 6 / channel 149 when the errors occurred (As  seen in some logs). So tried configuring the Netgear router to use channel 6/channel 149 but could not replicate the issue.
I just experienced another loss of wifi. This time using my cyan, while on a public wifi location (not my home OnHub). I filed a feedback report from this email account, with the tag #WiFi-out-of-range

At this point, I believe I have eliminated my Chromebook (happened with both my yuna and cyan), and my router (happened at home and away).

This time, I was not running any Android apps when the problem occured, so that probably eliminates Android issue.

Hello, 

It appears we are still seeing reports for this issue on the product forum as well. There have been 7 updated threads on this issue today. 

Thanks! 
Alisha 
From comment#17& #18, a couple of reports for Eve and Cyan devices. We will check if we can reproduce the issue on these devices first and report back. 
Labels: -Pri-2 M-69 Pri-1
Status: Untriaged (was: Unconfirmed)
Can reproduce this issue on Cyan M69-10888.0.0 

Sample failure below, 

2018-07-18T12:47:57.400388-07:00 NOTICE wpa_supplicant[732]: wlan0: SME: Trying to authenticate with c8:bc:c8:ff:2a:99 (SSID='CrOS_WPA2_Airport_Xtreme_2.4GHz' freq=2437 MHz)
2018-07-18T12:47:57.401633-07:00 INFO shill[1227]: [INFO:service.cc(400)] Service 1: state Idle -> Associating
2018-07-18T12:47:57.401668-07:00 INFO shill[1227]: [INFO:manager.cc(1456)] Service 1 updated; state: Associating failure Unknown
2018-07-18T12:47:57.402295-07:00 INFO shill[1227]: [INFO:service.cc(400)] Service 0: state Online -> Idle
2018-07-18T12:47:57.402322-07:00 INFO shill[1227]: [INFO:manager.cc(1456)] Service 0 updated; state: Idle failure Unknown
2018-07-18T12:47:57.409494-07:00 INFO shill[1227]: [INFO:manager.cc(1700)] Default physical service: 1 (not connected)
2018-07-18T12:47:57.409756-07:00 INFO shill[1227]: [INFO:service.cc(314)] Suppressed autoconnect to service 1 (connecting)
2018-07-18T12:47:57.409832-07:00 INFO shill[1227]: [INFO:wifi.cc(865)] WiFi wlan0 supplicant updated DisconnectReason to -3
2018-07-18T12:47:57.413905-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged completed -> disconnected
2018-07-18T12:47:57.418235-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged disconnected -> authenticating
2018-07-18T12:47:57.434696-07:00 NOTICE wpa_supplicant[732]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
2018-07-18T12:47:57.447826-07:00 NOTICE wpa_supplicant[732]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US
2018-07-18T12:47:57.696342-07:00 ERR wpa_supplicant[732]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
2018-07-18T12:47:57.696362-07:00 ERR wpa_supplicant[732]: nl80211: Failed to set IPv4 unicast in multicast filter
2018-07-18T12:47:57.702568-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged authenticating -> disconnected
2018-07-18T12:47:57.802578-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged disconnected -> scanning
2018-07-18T12:47:58.878060-07:00 INFO shill[1227]: [INFO:wifi.cc(395)] ScanDone
2018-07-18T12:47:58.882101-07:00 INFO shill[1227]: [INFO:service.cc(314)] Suppressed autoconnect to service 1 (connecting)
2018-07-18T12:47:58.898005-07:00 INFO shill[1227]: [INFO:wifi_service.cc(783)] Rep ep updated for 29. [SSID=CrOS_WPA2_Cisco_Valet+], bssid: 68:7f:74:c8:14:51, sig: -45, sec: rsn, freq: 2432
2018-07-18T12:47:58.907239-07:00 NOTICE wpa_supplicant[732]: wlan0: SME: Trying to authenticate with c8:bc:c8:ff:2a:99 (SSID='CrOS_WPA2_Airport_Xtreme_2.4GHz' freq=2437 MHz)
2018-07-18T12:47:58.913285-07:00 INFO shill[1227]: [INFO:service.cc(314)] Suppressed autoconnect to service 1 (connecting)
2018-07-18T12:47:58.956800-07:00 INFO shill[1227]: [INFO:wifi_service.cc(783)] Rep ep updated for 27. [SSID=CrOS_NONE_N300], bssid: e0:91:f5:6c:b8:e0, sig: -40, sec: none, freq: 2462
2018-07-18T12:47:58.959647-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged scanning -> authenticating
2018-07-18T12:47:59.266659-07:00 ERR wpa_supplicant[732]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
2018-07-18T12:47:59.266689-07:00 ERR wpa_supplicant[732]: nl80211: Failed to set IPv4 unicast in multicast filter
2018-07-18T12:47:59.273017-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged authenticating -> disconnected
2018-07-18T12:47:59.773222-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged disconnected -> scanning
2018-07-18T12:48:01.079963-07:00 NOTICE wpa_supplicant[732]: wlan0: SME: Trying to authenticate with c8:bc:c8:ff:2a:99 (SSID='CrOS_WPA2_Airport_Xtreme_2.4GHz' freq=2437 MHz)
2018-07-18T12:48:01.087422-07:00 INFO shill[1227]: [INFO:wifi.cc(395)] ScanDone
2018-07-18T12:48:01.093792-07:00 INFO shill[1227]: [INFO:service.cc(314)] Suppressed autoconnect to service 1 (connecting)
2018-07-18T12:48:01.146156-07:00 INFO shill[1227]: [INFO:wifi_service.cc(783)] Rep ep updated for 29. [SSID=CrOS_WPA2_Cisco_Valet+], bssid: 68:7f:74:c8:14:51, sig: -81, sec: rsn, freq: 2432
2018-07-18T12:48:01.154511-07:00 INFO shill[1227]: [INFO:wifi_service.cc(783)] Rep ep updated for 33. [SSID=dd-wrt_vap], bssid: ca:d7:19:86:f9:10, sig: -71, sec: none, freq: 2442
2018-07-18T12:48:01.162625-07:00 INFO shill[1227]: [INFO:service.cc(314)] Suppressed autoconnect to service 1 (connecting)
2018-07-18T12:48:01.166391-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged scanning -> authenticating
2018-07-18T12:48:01.431261-07:00 ERR wpa_supplicant[732]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
2018-07-18T12:48:01.431282-07:00 ERR wpa_supplicant[732]: nl80211: Failed to set IPv4 unicast in multicast filter
2018-07-18T12:48:01.437508-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged authenticating -> disconnected
2018-07-18T12:48:02.438084-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged disconnected -> scanning
2018-07-18T12:48:03.628079-07:00 NOTICE wpa_supplicant[732]: wlan0: SME: Trying to authenticate with c8:bc:c8:ff:2a:99 (SSID='CrOS_WPA2_Airport_Xtreme_2.4GHz' freq=2437 MHz)
2018-07-18T12:48:03.637781-07:00 INFO shill[1227]: [INFO:wifi.cc(395)] ScanDone
2018-07-18T12:48:03.641237-07:00 INFO shill[1227]: [INFO:service.cc(314)] Suppressed autoconnect to service 1 (connecting)
2018-07-18T12:48:03.679316-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged scanning -> authenticating
2018-07-18T12:48:03.680628-07:00 INFO shill[1227]: [INFO:wifi_service.cc(783)] Rep ep updated for 18. [SSID=wl-wearlab-G], bssid: 70:3a:0e:36:8a:00, sig: -62, sec: rsn, freq: 2462
2018-07-18T12:48:03.686177-07:00 INFO shill[1227]: [INFO:service.cc(314)] Suppressed autoconnect to service 1 (connecting)
2018-07-18T12:48:03.702027-07:00 INFO shill[1227]: [INFO:wifi_service.cc(783)] Rep ep updated for 33. [SSID=dd-wrt_vap], bssid: ca:d7:19:86:f9:10, sig: -45, sec: none, freq: 2442
2018-07-18T12:48:04.151934-07:00 NOTICE wpa_supplicant[732]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="CrOS_WPA2_Airport_Xtreme_2.4GHz" auth_failures=1 duration=10 reason=CONN_FAILED
2018-07-18T12:48:04.152016-07:00 ERR wpa_supplicant[732]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
2018-07-18T12:48:04.152027-07:00 ERR wpa_supplicant[732]: nl80211: Failed to set IPv4 unicast in multicast filter
2018-07-18T12:48:04.160232-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged authenticating -> disconnected
2018-07-18T12:48:09.159538-07:00 INFO shill[1227]: [INFO:wifi.cc(1543)] WiFi wlan0 StateChanged disconnected -> scanning
2018-07-18T12:48:11.614063-07:00 INFO shill[1227]: [INFO:wifi.cc(395)] ScanDone
2018-07-18T12:48:11.615030-07:00 INFO shill[1227]: [INFO:service.cc(314)] Suppressed autoconnect to service 1 (connecting)
2018-07-18T12:48:11.615360-07:00 INFO shill[1227]: [INFO:service.cc(292)] wifi service 34 constructed.
2018-07-18T12:48:11.615538-07:00 INFO shill[1227]: [INFO:wifi_service.cc(181)] Constructed WiFi service 34 name: [SSID=WL-play]
2018-07-18T12:48:11.615792-07:00 INFO shill[1227]: [INFO:wifi_service.cc(783)] Rep ep updated for 34. [SSID=WL-play], bssid: 6c:f3:7f:ae:89:43, sig: -92, sec: rsn, freq: 2412
2018-07-18T12:48:11.624125-07:00 INFO shill[1227]: [INFO:service.cc(298)] Service 31 destroyed.
2018-07-18T12:48:11.627525-07:00 INFO shill[1227]: [INFO:service.cc(314)] Suppressed autoconnect to service 1 (connecting)
2018-07-18T12:48:11.649577-07:00 INFO shill[1227]: [INFO:wifi_service.cc(783)] Rep ep updated for 29. [SSID=CrOS_WPA2_Cisco_Valet+], bssid: 68:7f:74:c8:14:51, sig: -61, sec: rsn, freq: 2432
2018-07-18T12:48:11.658002-07:00 INFO shill[1227]: [INFO:wifi_service.cc(783)] Rep ep updated for 18. [SSID=wl-wearlab-G], bssid: 70:3a:0e:36:8a:00, sig: -73, sec: rsn, freq: 2462
2018-07-18T12:48:11.676260-07:00 INFO shill[1227]: [INFO:wifi_service.cc(783)] Rep ep updated for 32. [SSID=CrOS_CISCO_VALETPLUS-guest], bssid: 68:7f:74:c8:14:52, sig: -82, sec: none, freq: 2432
2018-07-18T12:48:12.404008-07:00 INFO shill[1227]: [INFO:wifi.cc(2150)] WiFi Device wlan0: PendingTimeoutHandler
2018-07-18T12:48:12.404056-07:00 INFO shill[1227]: [INFO:service.cc(332)] Disconnecting from service 1: PendingTimeoutHandler
2018-07-18T12:48:12.404089-07:00 ERR shill[1227]: [ERROR:wifi.cc(971)] Disconnected due to inability to connect to the service.
2018-07-18T12:48:12.404238-07:00 INFO shill[1227]: [INFO:service.cc(400)] Service 1: state Associating -> Failure
2018-07-18T12:48:12.404265-07:00 INFO shill[1227]: [INFO:service.cc(986)] Noting an unexpected failure to connect.
2018-07-18T12:48:12.404306-07:00 INFO shill[1227]: [INFO:manager.cc(1456)] Service 1 updated; state: Failure failure out-of-range
2018-07-18T12:48:12.405115-07:00 INFO shill[1227]: [INFO:service.cc(400)] Service 1: state Failure -> Idle
2018-07-18T12:48:12.405195-07:00 INFO shill[1227]: [INFO:manager.cc(1456)] Service 1 updated; state: Idle failure Unknown
2018-07-18T12:48:12.406916-07:00 ERR shill[1227]: [ERROR:object_proxy.cc(582)] 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
2018-07-18T12:48:12.406953-07:00 ERR shill[1227]: [ERROR:dbus_method_invoker.h(111)] CallMethodAndBlockWithTimeout(...): Domain=dbus, Code=fi.w1.wpa_supplicant1.NotConnected, Message=This interface is not connected
2018-07-18T12:48:12.406982-07:00 ERR shill[1227]: [ERROR:chromeos_supplicant_interface_proxy.cc(196)] Failed to disconnect: fi.w1.wpa_supplicant1.NotConnected This interface is not connected
2018-07-18T12:48:12.409150-07:00 INFO shill[1227]: [INFO:dhcp_config.cc(210)] Stopping 1736 (ReleaseIP)
2018-07-18T12:48:12.409356-07:00 INFO dhcpcd[1736]: received SIGTERM, stopping
2018-07-18T12:48:12.409377-07:00 INFO dhcpcd[1736]: wlan0: removing interface
2018-07-18T12:48:12.412971-07:00 INFO dhcpcd[1736]: status changed to Release
2018-07-18T12:48:12.413313-07:00 INFO dhcpcd[1736]: dhcpcd exited
2018-07-18T12:48:12.417175-07:00 INFO shill[1227]: [INFO:service.cc(400)] Service 1: state Idle -> Failure
2018-07-18T12:48:12.417235-07:00 INFO shill[1227]: [INFO:manager.cc(1456)] Service 1 updated; state: Failure failure out-of-range
2018-07-18T12:48:12.418007-07:00 INFO shill[1227]: [INFO:service.cc(400)] Service 1: state Failure -> Idle

Feedback report: https://listnr.corp.google.com/report/85558095486. Will update the issue if we see it on M68 and M67. 
net.1.log
1.3 MB View Download
messages
64.7 KB View Download
Cc: benchan@chromium.org dwmclary@chromium.org rsch...@chromium.org steve...@chromium.org tbuck...@chromium.org
 Issue 863177  has been merged into this issue.
Owner: kirtika@chromium.org
Status: Assigned (was: Untriaged)
@kirtika is this something you could take a look at? I know multiple people who are unable to use their Pixelbooks at the SF airport due to out-of-range errors, despite every other device being able to connect.
Note - I just sent a feedback report tagged #WiFi-out-of-range. I still get occasional out of range errors.
I'm scouring the web for solutions to my frequent wifi drops (different routers/network setups, same difference). I've done a powerwash and all the basic troubleshooting advised by Google. I can't find a pattern as to what triggers the irrecoverable wifi crashes. This issue seems to be getting more severe with every Chrome version update.

My logs show the "ERR kernel: [ 6482.063693] pcieport 0000:00:1c.0: PCIe Bus Error: severity=Corrected" and the "Failed to wake NIC for hcmd" errors I've seen in several threads around here.

Log extract for GOOGLE Swanky, BIOS Google_Swanky.5216.238.150 [stable] Chrome version 69.0.3497.120 attached in case it helps. 

A fix would be much appreciated.
wake up NIC.txt
165 KB View Download

Sign in to add a comment