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

Issue 683269 link

Starred by 1 user
Status: WontFix
Owner:
steve...@chromium.org
Closed: Mar 2017
Cc:
█ jlklein@chromium.org
khorimoto@chromium.org
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 3
Type: Bug



Sign in to add a comment

NetworkConnect::OnConnectFailed is called on success states

Project Member Reported by hansberry@chromium.org, Jan 20 2017 
Device: veyron_minnie
Chrome Version: 57.0.2967.0
OS: 9086.0.0

What steps will reproduce the problem?
(1) Successfully connect to a network via NetworkConnect::ConnectToNetworkId (the exact method may not matter)

What is the expected result?
NetworkConnect::OnConnectFailed should not be called, because the connection succeeded.

What happens instead?
NetworkConnect::OnConnectFailed is called. Logs included below, connecting to SSID "Instant Tethering 7fe84". I inserted a custom log in NetworkConnect::OnConnectFailed:

[28765:28765:0118/131000.605398:VERBOSE1:device_event_log_impl.cc(142)] [13:10:00.605] Network: DEBUG: network_state_handler.cc:955 NOTIFY:NetworkPropertiesUpdated: Instant Tethering 7fe84 (/service/11040)
[28765:28765:0118/131004.831749:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.829] Network: USER: network_connect.cc:537 CreateConfigurationAndConnect
[28765:28765:0118/131004.835328:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.834] Network: USER: network_configuration_handler.cc:308 CreateShillConfiguration: wifi: Unidentified wifi
[28765:28765:0118/131004.840684:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.839] Network: USER: network_configuration_handler.cc:72 Configure: wifi.AutoConnect=true
[28765:28765:0118/131004.843678:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.842] Network: USER: network_configuration_handler.cc:72 Configure: wifi.GUID="b6b0a9b4-8adb-444b-a0cd-2bc6858bcb92"
[28765:28765:0118/131004.846319:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.845] Network: USER: network_configuration_handler.cc:72 Configure: wifi.Passphrase=******
[28765:28765:0118/131004.848969:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.848] Network: USER: network_configuration_handler.cc:72 Configure: wifi.Profile="/profile/default"
[28765:28765:0118/131004.852826:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.850] Network: USER: network_configuration_handler.cc:72 Configure: wifi.SaveCredentials=true
[28765:28765:0118/131004.853115:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.853] Network: USER: network_configuration_handler.cc:72 Configure: wifi.SecurityClass="psk"
[28765:28765:0118/131004.853357:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.853] Network: USER: network_configuration_handler.cc:72 Configure: wifi.Type="wifi"
[28765:28765:0118/131004.853601:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.853] Network: USER: network_configuration_handler.cc:72 Configure: wifi.WiFi.HexSSID="496E7374616E7420546574686572696E67203766653834"
[28765:28765:0118/131004.858704:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.858] Network: EVENT: network_state_handler.cc:624 NetworkPropertyUpdated: Instant Tethering 7fe84.GUID = "b6b0a9b4-8adb-444b-a0cd-2bc6858bcb92"
[28765:28765:0118/131004.858999:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.858] Network: DEBUG: network_state_handler.cc:955 NOTIFY:NetworkPropertiesUpdated: Instant Tethering 7fe84 (/service/11040)
[28765:28765:0118/131004.882610:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.882] Network: USER: network_connect.cc:278 Configure Succeeded: b6b0a9b4-8adb-444b-a0cd-2bc6858bcb92
[28765:28765:0118/131004.883791:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.883] Network: USER: network_connection_handler.cc:229 ConnectToNetwork: /service/11040
[28765:28765:0118/131004.884039:ERROR:device_event_log_impl.cc(140)] [13:10:04.883] Network: network_connection_handler.cc:789 Connect Failure: connected: /service/11040
[28765:28765:0118/131004.884230:ERROR:network_connect.cc(189)] tether: OnConnectFailed, errorname: connected
[28765:28765:0118/131004.884440:ERROR:device_event_log_impl.cc(140)] [13:10:04.884] Network: network_connect.cc:199 Connect Failed: connected: b6b0a9b4-8adb-444b-a0cd-2bc6858bcb92
[28765:28765:0118/131004.884706:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.884] Network: EVENT: network_state_handler.cc:368 RequestUpdate: /service/11040
[28765:28765:0118/131004.889501:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.889] Network: DEBUG: network_state_handler.cc:531 Network Properties Received: Instant Tethering 7fe84 (/service/11040)
[28765:28765:0118/131004.889907:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.889] Network: EVENT: network_state.cc:207 InitialPropertiesReceived: /service/11040: Instant Tethering 7fe84 State: online Visible: 1
[28765:28765:0118/131004.890231:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.890] Network: EVENT: network_state_handler.cc:569 NetworkPropertiesUpdated: Instant Tethering 7fe84 (/service/11040)
[28765:28765:0118/131004.890467:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.890] Network: DEBUG: network_state_handler.cc:955 NOTIFY:NetworkPropertiesUpdated: Instant Tethering 7fe84 (/service/11040)
[28765:28765:0118/131004.891422:VERBOSE1:device_event_log_impl.cc(142)] [13:10:04.891] Network: EVENT: network_state_handler.cc:722 NOTIFY:NetworkListChanged: Size:32

Comment 1 by steve...@chromium.org, Mar 2 2017 

hansberry@ - Can you check to see if this is still happening? It might be related to  issue 662571  which has been fixed.

The error in question is "connected" which occurs when attempting to connect to an already connected network, so also check that the network was not connected before the connect attempt.

Comment 2 by hansberry@chromium.org, Mar 2 2017

Status: WontFix (was: Untriaged)
It seems this bug no longer exists :) Logs below (after doing the exact same thing) indicate only success:

[11092:11092:0302/104509.419648:VERBOSE1:device_event_log_impl.cc(139)] [10:45:09.419] Network: USER: network_configuration_handler.cc:306 CreateShillConfiguration: wifi: d6e40f6e-a84b-4435-b5bf-e62a465ef50b
[11092:11092:0302/104509.419811:VERBOSE1:device_event_log_impl.cc(139)] [10:45:09.419] Network: USER: network_configuration_handler.cc:72 Configure: wifi.AutoConnect=false
[11092:11092:0302/104509.419949:VERBOSE1:device_event_log_impl.cc(139)] [10:45:09.419] Network: USER: network_configuration_handler.cc:72 Configure: wifi.GUID="d6e40f6e-a84b-4435-b5bf-e62a465ef50b"
[11092:11092:0302/104509.420082:VERBOSE1:device_event_log_impl.cc(139)] [10:45:09.420] Network: USER: network_configuration_handler.cc:72 Configure: wifi.Passphrase=******
[11092:11092:0302/104509.420214:VERBOSE1:device_event_log_impl.cc(139)] [10:45:09.420] Network: USER: network_configuration_handler.cc:72 Configure: wifi.Profile="/profile/default"
[11092:11092:0302/104509.420346:VERBOSE1:device_event_log_impl.cc(139)] [10:45:09.420] Network: USER: network_configuration_handler.cc:72 Configure: wifi.SaveCredentials=true
[11092:11092:0302/104509.420478:VERBOSE1:device_event_log_impl.cc(139)] [10:45:09.420] Network: USER: network_configuration_handler.cc:72 Configure: wifi.SecurityClass="psk"
[11092:11092:0302/104509.420609:VERBOSE1:device_event_log_impl.cc(139)] [10:45:09.420] Network: USER: network_configuration_handler.cc:72 Configure: wifi.Type="wifi"
[11092:11092:0302/104509.420741:VERBOSE1:device_event_log_impl.cc(139)] [10:45:09.420] Network: USER: network_configuration_handler.cc:72 Configure: wifi.WiFi.HexSSID="496E7374616E7420546574686572696E67206464323664"
[11092:11092:0302/104509.455525:VERBOSE1:device_event_log_impl.cc(139)] [10:45:09.455] Network: USER: network_connection_handler.cc:229 ConnectToNetwork: /service/5702
[11092:11092:0302/104509.455742:VERBOSE1:device_event_log_impl.cc(139)] [10:45:09.455] Network: EVENT: network_connection_handler.cc:628 Sending Connect Request to Shill: /service/5702
[11092:11092:0302/104509.491750:VERBOSE1:device_event_log_impl.cc(139)] [10:45:09.491] Network: EVENT: network_connection_handler.cc:663 Connect Request Acknowledged: /service/5702
[11092:11092:0302/104525.506107:VERBOSE1:device_event_log_impl.cc(139)] [10:45:25.506] Network: EVENT: network_connection_handler.cc:763 Connect Request Succeeded: /service/5702
►

Sign in to add a comment