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

Issue 882544 link

Starred by 2 users

Issue metadata

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



Sign in to add a comment

Intempestive WiFi disconnections

Project Member Reported by fbeaufort@chromium.org, Sep 10

Issue description

Google Chrome	71.0.3544.0 (Official Build) canary (64-bit)
Revision	f80e8674cf9bfd56f155979637e9f41eaebf995c-refs/branch-heads/3544@{#1}
Platform	11053.0.0 (Official Build) canary-channel eve
Firmware Version	Google_Eve.9584.160.0
Customization ID	GOOGLE-EVE
ARC	4998684


WiFi disconnects often (every 20 30s).
Here are my logs when it occurs:

2018-09-10T20:54:42.664321+02:00 INFO kernel: [  451.122452] wlan0: authenticate with 4a:9a:6d:77:82:40
2018-09-10T20:54:42.668681+02:00 INFO kernel: [  451.126985] wlan0: send auth to 4a:9a:6d:77:82:40 (try 1/3)
2018-09-10T20:54:42.782253+02:00 INFO kernel: [  451.240574] wlan0: authenticated
2018-09-10T20:54:42.784199+02:00 INFO kernel: [  451.242128] wlan0: associate with 4a:9a:6d:77:82:40 (try 1/3)
2018-09-10T20:54:42.914178+02:00 INFO kernel: [  451.372105] wlan0: associate with 4a:9a:6d:77:82:40 (try 2/3)
2018-09-10T20:54:42.957235+02:00 INFO kernel: [  451.415491] wlan0: RX AssocResp from 4a:9a:6d:77:82:40 (capab=0x411 status=0 aid=3)
2018-09-10T20:54:42.961187+02:00 INFO kernel: [  451.419803] wlan0: associated
2018-09-10T20:54:45.403169+02:00 INFO kernel: [  453.861651] wlan0: deauthenticated from 4a:9a:6d:77:82:40 (Reason: 3=DEAUTH_LEAVING)
2018-09-10T20:54:45.422910+02:00 ERR wpa_supplicant[613]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
2018-09-10T20:54:45.422914+02:00 ERR wpa_supplicant[613]: nl80211: Failed to set IPv4 unicast in multicast filter
2018-09-10T20:54:45.428506+02:00 ERR shill[1632]: [ERROR:wifi.cc(988)] Disconnected due to inability to connect to the service.
2018-09-10T20:54:45.440143+02:00 INFO kernel: [  453.898689] wlan0: authenticate with 4a:9a:6d:77:82:40
2018-09-10T20:54:45.444185+02:00 INFO kernel: [  453.902611] wlan0: send auth to 4a:9a:6d:77:82:40 (try 1/3)
2018-09-10T20:54:45.449187+02:00 INFO kernel: [  453.907875] wlan0: authenticated
2018-09-10T20:54:45.451147+02:00 INFO kernel: [  453.909250] wlan0: associate with 4a:9a:6d:77:82:40 (try 1/3)
2018-09-10T20:54:45.460159+02:00 INFO kernel: [  453.918297] wlan0: RX AssocResp from 4a:9a:6d:77:82:40 (capab=0x411 status=0 aid=3)
2018-09-10T20:54:45.461163+02:00 INFO kernel: [  453.919905] wlan0: associated
2018-09-10T20:54:46.503481+02:00 INFO avahi-daemon[1989]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.13.
2018-09-10T20:54:46.503914+02:00 INFO avahi-daemon[1989]: New relevant interface wlan0.IPv4 for mDNS.
2018-09-10T20:54:46.503933+02:00 INFO avahi-daemon[1989]: Registering new address record for 192.168.0.13 on wlan0.IPv4.


Is there a workaround for now?
 
Cc: harpreet@chromium.org briannorris@chromium.org fbeaufort@chromium.org
Labels: -Pri-3 Pri-2
Owner: kirtika@chromium.org
> 2018-09-10T20:54:45.422910+02:00 ERR wpa_supplicant[613]: nl80211: Failed to > open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
> 2018-09-10T20:54:45.422914+02:00 ERR wpa_supplicant[613]: nl80211: Failed to > set IPv4 unicast in multicast filter


This is a benign error/red herring. 
I'll need the full logs to understand what is going on (step 2 of go/chromeos-feedback-faq). Since you are based in a non-US geo (which is not something we test/update very well for wifi regulatory), my suspicion would be something to do with that. 

- Navigate to chrome://net-internals#chromeos in your browser. 
- Hit ‘Store debug logs’. 
- This will store a debug-logs_<timestamp>.tgz file in your Downloads folder. 
Share this tarball. 

Comment 3 Deleted

And here are some logs that may help:

2018-09-12T19:30:03.902510+02:00 ERR debugd[1566]: Failed to call method: org.freedesktop.DBus.Properties.Set: object_path= /f1/w1/wpa_supplicant1: org.freedesktop.DBus.Error.UnknownMethod: Method "Set" with signature "ssv" on interface "org.freedesktop.DBus.Properties" doesn't exist#012
2018-09-12T19:30:04.188883+02:00 WARNING kernel: [  811.298999] net_ratelimit: 444 callbacks suppressed
2018-09-12T19:30:08.146551+02:00 INFO kernel: [  815.257345] wlan0: deauthenticated from 4a:9a:6d:77:82:40 (Reason: 3=DEAUTH_LEAVING)
2018-09-12T19:30:08.232019+02:00 ERR wpa_supplicant[612]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
2018-09-12T19:30:08.232021+02:00 ERR wpa_supplicant[612]: nl80211: Failed to set IPv4 unicast in multicast filter
2018-09-12T19:30:08.237650+02:00 ERR shill[1629]: [ERROR:wifi.cc(988)] Disconnected due to inability to connect to the service.
2018-09-12T19:30:08.256019+02:00 INFO avahi-daemon[1971]: Withdrawing address record for 192.168.0.13 on wlan0.
2018-09-12T19:30:08.256050+02:00 INFO avahi-daemon[1971]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.13.
2018-09-12T19:30:08.256063+02:00 INFO avahi-daemon[1971]: Interface wlan0.IPv4 no longer relevant for mDNS.
2018-09-12T19:30:08.266856+02:00 NOTICE kernel: [  815.376898] audit: type=1400 audit(1536773408.265:406): avc:  granted  { execute } for  pid=8523 comm="init" name="dash" dev="dm-0" ino=8281 scontext=u:r:cros_init:s0 tcontext=u:object_r:sh_exec:s0 tclass=file
2018-09-12T19:30:08.266871+02:00 NOTICE kernel: [  815.377123] audit: type=1400 audit(1536773408.265:407): avc:  granted  { execute } for  pid=8523 comm="cros-machine-id" path="/bin/dash" dev="dm-0" ino=8281 scontext=u:r:cros_init:s0 tcontext=u:object_r:sh_exec:s0 tclass=file
2018-09-12T19:30:08.367015+02:00 INFO avahi-daemon[1971]: Withdrawing address record for 100.115.92.1 on arcbr0.
2018-09-12T19:30:08.367043+02:00 INFO avahi-daemon[1971]: Registering new address record for 100.115.92.1 on arcbr0.IPv4.
2018-09-12T19:30:08.367116+02:00 INFO avahi-daemon[1971]: Changing host name to '61c3cc1d1a7cbf8761ad2a6a5b994d20'.
2018-09-12T19:30:08.374919+02:00 NOTICE cros-machine-id-regen[8577]: Regenerated /var/lib/dbus/machine-id (reason: network).
2018-09-12T19:30:08.546861+02:00 INFO kernel: [  815.657154] Bluetooth: hci_core.c:skip_conditional_cmd()   COND LE cmd (0x200a) is already 0 (chg 0), skip transition to 0
2018-09-12T19:30:08.546872+02:00 INFO kernel: [  815.657160] Bluetooth: hci_core.c:skip_conditional_cmd()   COND call queue_work.
2018-09-12T19:30:09.225175+02:00 INFO avahi-daemon[1971]: Server startup complete. Host name is 61c3cc1d1a7cbf8761ad2a6a5b994d20.local. Local service cookie is 3889979352.
2018-09-12T19:30:09.294373+02:00 INFO kernel: [  816.404934] wlan0: authenticate with 4a:9a:6d:77:82:40
2018-09-12T19:30:09.302590+02:00 INFO kernel: [  816.412396] wlan0: send auth to 4a:9a:6d:77:82:40 (try 1/3)
2018-09-12T19:30:09.305903+02:00 WARNING kernel: [  816.416424] net_ratelimit: 936 callbacks suppressed
2018-09-12T19:30:09.305907+02:00 INFO kernel: [  816.416445] wlan0: authenticated
2018-09-12T19:30:09.307860+02:00 INFO kernel: [  816.417800] wlan0: associate with 4a:9a:6d:77:82:40 (try 1/3)
2018-09-12T19:30:09.316977+02:00 INFO kernel: [  816.426880] wlan0: RX AssocResp from 4a:9a:6d:77:82:40 (capab=0x411 status=0 aid=4)
2018-09-12T19:30:09.327954+02:00 INFO kernel: [  816.438635] wlan0: associated
2018-09-12T19:30:11.133421+02:00 INFO avahi-daemon[1971]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.13.
2018-09-12T19:30:11.133601+02:00 INFO avahi-daemon[1971]: New relevant interface wlan0.IPv4 for mDNS.
2018-09-12T19:30:11.133612+02:00 INFO avahi-daemon[1971]: Registering new address record for 192.168.0.13 on wlan0.IPv4.
2018-09-12T19:30:14.378644+02:00 WARNING kernel: [  821.489343] net_ratelimit: 128 callbacks suppressed
2018-09-12T19:30:15.753202+02:00 WARNING mosys.elf[9005]: libminijail[9005]: allowing syscall: socket
2018-09-12T19:30:15.753217+02:00 WARNING mosys.elf[9005]: libminijail[9005]: allowing syscall: connect
2018-09-12T19:30:15.753223+02:00 WARNING mosys.elf[9005]: libminijail[9005]: allowing syscall: sendto
2018-09-12T19:30:15.753228+02:00 WARNING mosys.elf[9005]: libminijail[9005]: allowing syscall: writev
2018-09-12T19:30:15.763124+02:00 WARNING mosys.elf[9005]: libminijail[9005]: not locking any securebits
2018-09-12T19:30:15.763395+02:00 WARNING mosys.elf[9005]: libminijail[9005]: SECURE_NOROOT not set, not dropping bounding set
2018-09-12T19:30:15.763422+02:00 WARNING mosys.elf[9005]: libminijail[9005]: logging seccomp filter failures
2018-09-12T19:30:16.191784+02:00 INFO kernel: [  823.302704] wlan0: deauthenticated from 4a:9a:6d:77:82:40 (Reason: 3=DEAUTH_LEAVING)
2018-09-12T19:30:16.272027+02:00 ERR wpa_supplicant[612]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory
2018-09-12T19:30:16.272031+02:00 ERR wpa_supplicant[612]: nl80211: Failed to set IPv4 unicast in multicast filter
2018-09-12T19:30:16.280216+02:00 ERR shill[1629]: [ERROR:wifi.cc(988)] Disconnected due to inability to connect to the service.
2018-09-12T19:30:16.303382+02:00 INFO avahi-daemon[1971]: Withdrawing address record for 192.168.0.13 on wlan0.
2018-09-12T19:30:16.303407+02:00 INFO avahi-daemon[1971]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.13.
2018-09-12T19:30:16.305496+02:00 INFO avahi-daemon[1971]: Interface wlan0.IPv4 no longer relevant for mDNS.
2018-09-12T19:30:16.316852+02:00 NOTICE kernel: [  823.427876] audit: type=1400 audit(1536773416.315:408): avc:  granted  { execute } for  pid=9054 comm="init" name="dash" dev="dm-0" ino=8281 scontext=u:r:cros_init:s0 tcontext=u:object_r:sh_exec:s0 tclass=file
2018-09-12T19:30:16.317850+02:00 NOTICE kernel: [  823.428037] audit: type=1400 audit(1536773416.316:409): avc:  granted  { execute } for  pid=9054 comm="cros-machine-id" path="/bin/dash" dev="dm-0" ino=8281 scontext=u:r:cros_init:s0 tcontext=u:object_r:sh_exec:s0 tclass=file
2018-09-12T19:30:16.351942+02:00 INFO avahi-daemon[1971]: Withdrawing address record for 100.115.92.1 on arcbr0.
2018-09-12T19:30:16.351966+02:00 INFO avahi-daemon[1971]: Registering new address record for 100.115.92.1 on arcbr0.IPv4.
2018-09-12T19:30:16.352027+02:00 INFO avahi-daemon[1971]: Changing host name to '9750736c098a2162ffe8a3c65b994d28'.
2018-09-12T19:30:16.354525+02:00 NOTICE cros-machine-id-regen[9074]: Regenerated /var/lib/dbus/machine-id (reason: network).
2018-09-12T19:30:16.801422+02:00 ERR debugd[1566]: Failed to call method: org.freedesktop.DBus.Properties.Set: object_path= /f1/w1/wpa_supplicant1: org.freedesktop.DBus.Error.UnknownMethod: Method "Set" with signature "ssv" on interface "org.freedesktop.DBus.Properties" doesn't exist#012
2018-09-12T19:30:17.209317+02:00 INFO avahi-daemon[1971]: Server startup complete. Host name is 9750736c098a2162ffe8a3c65b994d28.local. Local service cookie is 3889979352.
2018-09-12T19:30:19.986190+02:00 INFO kernel: [  827.096924] wlan0: authenticate with 4a:9a:6d:77:82:40
2018-09-12T19:30:19.990852+02:00 INFO kernel: [  827.101455] wlan0: send auth to 4a:9a:6d:77:82:40 (try 1/3)
2018-09-12T19:30:19.997854+02:00 INFO kernel: [  827.107977] wlan0: authenticated
2018-09-12T19:30:19.998858+02:00 INFO kernel: [  827.108986] wlan0: associate with 4a:9a:6d:77:82:40 (try 1/3)
2018-09-12T19:30:20.007857+02:00 INFO kernel: [  827.118906] wlan0: RX AssocResp from 4a:9a:6d:77:82:40 (capab=0x411 status=0 aid=4)
2018-09-12T19:30:20.012876+02:00 INFO kernel: [  827.123634] wlan0: associated
2018-09-12T19:30:20.863377+02:00 INFO kernel: [  827.974347] Bluetooth: hci_core.c:skip_conditional_cmd()   COND LE cmd (0x200a) is already 0 (chg 0), skip transition to 0
2018-09-12T19:30:20.863401+02:00 INFO kernel: [  827.974369] Bluetooth: hci_core.c:skip_conditional_cmd()   COND call queue_work.
2018-09-12T19:30:21.348256+02:00 INFO avahi-daemon[1971]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.13.
2018-09-12T19:30:21.348756+02:00 INFO avahi-daemon[1971]: New relevant interface wlan0.IPv4 for mDNS.
2018-09-12T19:30:21.348769+02:00 INFO avahi-daemon[1971]: Registering new address record for 192.168.0.13 on wlan0.IPv4.
Status: Assigned (was: Untriaged)
This issue has an owner, a component and a priority, but is still listed as untriaged or unconfirmed. By definition, this bug is triaged. Changing status to "assigned". Please reach out to me if you disagree with how I've done this.

Sign in to add a comment