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

Issue 902887 link

Starred by 5 users

Issue metadata

Status: Assigned
Merged: issue 904837
Owner:
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 2
Type: Bug-Regression



Sign in to add a comment

Device ignores WiFi network from policy

Project Member Reported by vkhabarov@chromium.org, Nov 7

Issue description

ChromeOS version: v69+
ChromeOS device model: auron_paine 
Case#: 17361750

Description:
Customer states that devices on v68 are automatically connecting to WiFi SSID from user policy, but after upgrade to v69-v70 they would only connect to it if manually selected . Network policies are the same
Trying to repro in lab

Steps to reproduce: 
1. Setup network in Admin Console in user settings (in this case hidden and WPA2, not sure if important)
2. Login with user from corresponding OU

Current Behavior / Reproduction: 
Device won't autoconnect to this network

Expected Behavior: 
Device would connect to this network

Drive link to logs: 
https://drive.google.com/drive/folders/1qUcQVO0E9jfQBgbQ6KuxA8Spk_mBmtBP?usp=sharing

I noticed in logs that this network is being skipped for some reason - 
net.1.log:2018-11-02T18:41:29.571875+00:00 DEBUG wpa_supplicant[466]: wlan0: 2: f8:c2:88:74:ce:0c ssid='_deleted_' wpa_ie_len=24 rsn_ie_len=20 caps=0x1011 level=-53 freq=5180 
net.1.log-2018-11-02T18:41:29.571887+00:00 DEBUG wpa_supplicant[466]: wlan0:    skip - SSID mismatch
--
net.1.log:2018-11-02T18:41:29.571935+00:00 DEBUG wpa_supplicant[466]: wlan0: 5: f8:c2:88:74:ce:03 ssid='_deleted_' wpa_ie_len=24 rsn_ie_len=20 caps=0x1431 level=-47 freq=2462 
net.1.log-2018-11-02T18:41:29.571940+00:00 DEBUG wpa_supplicant[466]: wlan0:    skip - SSID mismatch
--
net.1.log:2018-11-02T18:41:29.571962+00:00 DEBUG wpa_supplicant[466]: wlan0: 7: f8:c2:88:74:d8:33 ssid='_deleted_' wpa_ie_len=24 rsn_ie_len=20 caps=0x1431 level=-67 freq=2412 
net.1.log-2018-11-02T18:41:29.571967+00:00 DEBUG wpa_supplicant[466]: wlan0:    skip - SSID mismatch
--
net.1.log:2018-11-02T18:41:29.572008+00:00 DEBUG wpa_supplicant[466]: wlan0: 10: f8:c2:88:74:1e:3c ssid='_deleted_' wpa_ie_len=24 rsn_ie_len=20 caps=0x1011 level=-77 freq=5220 
net.1.log-2018-11-02T18:41:29.572013+00:00 DEBUG wpa_supplicant[466]: wlan0:    skip - SSID mismatch
--
net.1.log:2018-11-02T18:41:29.572055+00:00 DEBUG wpa_supplicant[466]: wlan0: 13: f8:c2:88:74:d8:3c ssid='_deleted_' wpa_ie_len=24 rsn_ie_len=20 caps=0x1011 level=-80 freq=5300 
net.1.log-2018-11-02T18:41:29.572059+00:00 DEBUG wpa_supplicant[466]: wlan0:    skip - SSID mismatch
--
net.1.log:2018-11-02T12:56:13.833661-07:00 DEBUG wpa_supplicant[510]: wlan0: 2: f8:c2:88:74:ce:0c ssid='_deleted_' wpa_ie_len=24 rsn_ie_len=20 caps=0x1011 level=-49 freq=5180 
net.1.log-2018-11-02T12:56:13.833670-07:00 DEBUG wpa_supplicant[510]: wlan0:    skip - blacklisted (count=2 limit=0)
--
net.1.log:2018-11-02T12:56:13.833729-07:00 DEBUG wpa_supplicant[510]: wlan0: 5: f8:c2:88:74:ce:03 ssid='_deleted_' wpa_ie_len=24 rsn_ie_len=20 caps=0x1431 level=-47 freq=2462 
net.1.log-2018-11-02T12:56:13.833735-07:00 DEBUG wpa_supplicant[510]: wlan0:    skip - disabled

 
Hello Team, do we have any updates on this issue? 
Cc: eryen@chromium.org hendrich@chromium.org marcuskoehler@chromium.org
Cc: ryutas@chromium.org
Alex, Marcus,

Is this the expected behavior as explained in 
https://bugs.chromium.org/p/chromium/issues/detail?id=559656#c20

for the Chrome OS device Wifi prioritization? 
in M68: (service 3)

2018-11-06T09:20:09.832823-08:00 INFO shill[975]: [INFO:service.cc(290)] wifi service 3 constructed.
2018-11-06T09:20:09.832937-08:00 INFO shill[975]: [INFO:wifi_service.cc(184)] Constructed WiFi service 3 name: [SSID=HIS_FAVORITE_WIFI]
2018-11-06T09:22:33.974797-08:00 INFO shill[975]: [INFO:service.cc(1470)] Service 3: AutoConnect=0->1

in M69/M70 (service 11)
2018-11-05T13:34:27.770369-08:00 INFO shill[1091]: [INFO:service.cc(291)] wifi service 11 constructed.
2018-11-05T13:34:27.770432-08:00 INFO shill[1091]: [INFO:wifi_service.cc(184)] Constructed WiFi service 11 name: [SSID=HIS_FAVORITE_WIFI]
2018-11-05T13:34:27.770561-08:00 INFO shill[1091]: [INFO:service.cc(1474)] Service 11: AutoConnect=0->1

This is unrelated to 559656 for several reasons:
1) 559656 is fixed with M70
2) 559656 actually prefers wifi over ethernet (but connect's to both)
3) 559656 doesn't change the autoconnect behavior

+kirtika for wpa_supplicant expertise
Cc: aghuie@chromium.org
+aghuie who covers networking topics from the PM side

Comment 7 Deleted

I took a look at the discussion in 559656 and I agree that it should auto connect to the network pushed down via user policy vs any configured network during enrollment (5th prioritization sorting order)

It doesn't look like changes in 559656 would impact that but can someone explain to me what we did change for 69/70? In case we are missing something else that might be configured for the customer's network that affected prioritization.
Was the device connected to another network when it should have auto-connected to the policy network (i.e. swap network) or was it unconnected and should create a new connection?
Yes, it was connected. Prior to 69+ the behavior is that device was connected to another network that was defined via device policy. On login with account on Chromebook that has user defined network policy the  network would switch ( swap network).  
Just an FYI, Reading through other bug reports seems to be the same issue as reported https://bugs.chromium.org/p/chromium/issues/detail?id=559656#c26 and https://bugs.chromium.org/p/chromium/issues/detail?id=904837 . 


Mergedinto: 904837
Owner: hendrich@chromium.org
Status: Duplicate (was: Untriaged)
Cc: steve...@chromium.org
Owner: pmarko@chromium.org
Status: Assigned (was: Duplicate)
In the feedback report from M-70, I can see two services with managed credentials:

/service/1:
SSID: <A>
ManagedCredentials: true
Profile: default (shared)

/service/7
SSID: <B>
ManagedCredentials: true
Profile: chronos (user)

There's no HasEverConnected but that seems to be because this is generated using a GetProperties dbus call, and HasEverConnected is not in the "PropertyStore". Steven/Alex, would it make sense to add it there for diagnostics purposes at least?

Anyways, net.log shows:
2018-11-05T11:16:54.678802-08:00 INFO shill[1086]: [INFO:service.cc(305)] Suppressed autoconnect to service 7 (no endpoints)
2018-11-05T11:16:58.781127-08:00 INFO shill[1086]: [INFO:wifi_service.cc(798)] Rep ep updated for 7. [SSID=....], bssid: ...., sig: -50, sec: rsn, freq: 5260
(nothing interesting)


In the M-68 log (where it works), the same service is under /service/6, and there's a similar message in net.log, but then it follows with:
2018-11-02T12:56:12.712992-07:00 INFO shill[1084]: [INFO:service.cc(312)] Suppressed autoconnect to service 6 (no endpoints)

2018-11-02T12:56:12.713666-07:00 INFO shill[1084]: [INFO:wifi_service.cc(798)] Rep ep updated for 6. [SSID=....], bssid: ...., sig: -49, sec: rsn, freq: 5180
2018-11-02T12:56:12.816417-07:00 INFO shill[1084]: [INFO:service.cc(319)] Connect to service 6: ConnectToBestServicesTask

This is missing from the M-69 log.
I'll try to see what triggers this usually.
I thought this was a duplicate of 904837 as well, isn't it?
Does the auto-connect problem get fixed once you have manually connected to the user policy network?
Cc: -marcuskoehler@chromium.org

Comment 16 by pmarko@chromium.org, Jan 17 (5 days ago)

I was on leave, sorry for the delay.

Does this still repro? If yes, could we get the chrome://policy export?
Thanks!

Sign in to add a comment