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

Issue 752337 link

Starred by 2 users
Status: Untriaged
Owner: ----
Cc:
█ jlklein@chromium.org
█ lesliewatkins@chromium.org
maybelle@chromium.org
khorimoto@chromium.org
█ kirtika@chromium.org
█ cernekee@chromium.org
jhawkins@chromium.org
hansberry@chromium.org
steve...@chromium.org
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 3
Type: Bug



Sign in to add a comment

Intermittent inability to connect to Wi-Fi hotspot

Project Member Reported by khorimoto@chromium.org, Aug 3 2017 
Device: Kefka

We successfully completed sending/receiving tethering protocol messages, then started connecting to the Wi-Fi hotspot network.

Eventually, the connection attempt fails. Device logs are below.

NetworkEvent[16:01:46] NOTIFY: NetworkConnectionStateChanged: Huawei Nexus 6P (CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==): disconnect
NetworkEvent[16:01:46] NetworkingPrivate.OnPortalDetectionCompleted: No Listeners
NetworkEvent[16:01:46] Default network changed: None
NetworkEvent[16:01:46] NOTIFY:DefaultNetworkChanged: None
NetworkError[16:01:46] Default network in unexpected state: Huawei Nexus 6P (CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==)State: disconnect
NetworkEvent[16:01:46] Changing connection state for Tether network with GUID CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==. Old state: configuration, New state: disconnect
NetworkError[16:01:46] Connect Failed: connect-failed: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkError[16:01:46] Connect Failure: connect-failed: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkUser[16:01:38] GetShillProperties: /service/52
NetworkEvent[16:01:38] NOTIFY:NetworkListChanged: Size:32
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/94
NetworkEvent[16:01:38] NetworkPropertiesUpdated: cantu (/service/94)
NetworkEvent[16:01:38] NOTIFY: NetworkConnectionStateChanged: cantu (/service/94): idle
NetworkEvent[16:01:38] InitialPropertiesReceived: /service/94: cantu State: idle Visible: 1
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/93
NetworkEvent[16:01:38] NetworkPropertiesUpdated: It Hurts When IP (/service/93)
NetworkEvent[16:01:38] NOTIFY: NetworkConnectionStateChanged: It Hurts When IP (/service/93): idle
NetworkEvent[16:01:38] InitialPropertiesReceived: /service/93: It Hurts When IP State: idle Visible: 1
NetworkUser[16:01:38] Device.SetProperty: MACAddressRandomization
NetworkEvent[16:01:38] DevicePropertyUpdated: wlan0.Scanning = false
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/15
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/14
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/77
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/1
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/82
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:38] NetworkPropertyUpdated: SambaNetwork.WiFi.BSSID = "a4:2b:b0:c6:d1:45"
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:38] NetworkPropertyUpdated: SambaNetwork.WiFi.Frequency = 2412
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:38] NetworkPropertyUpdated: SambaNetwork.WiFi.BSSID = "38:d5:47:db:ad:28"
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:38] NetworkPropertyUpdated: SambaNetwork.WiFi.Frequency = 2452
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/9
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/8
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/7
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/6
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/68
NetworkEvent[16:01:38] NetworkingPrivate.NetworkPropertiesUpdated: /service/5
NetworkUser[16:01:38] GetShillProperties: /service/52
NetworkEvent[16:01:38] NOTIFY:NetworkListChanged: Size:30
NetworkUser[16:01:35] GetShillProperties: /service/52
NetworkEvent[16:01:35] NOTIFY:NetworkListChanged: Size:30
NetworkEvent[16:01:35] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkEvent[16:01:35] NetworkPropertiesUpdated: Instant Tethering dccbd (/service/81)
NetworkEvent[16:01:35] InitialPropertiesReceived: /service/81: Instant Tethering dccbd State: idle Visible: 1
NetworkUser[16:01:35] Device.SetProperty: MACAddressRandomization
NetworkEvent[16:01:35] DevicePropertyUpdated: wlan0.Scanning = true
NetworkEvent[16:01:35] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkEvent[16:01:35] RequestUpdate: /service/81
NetworkEvent[16:01:35] NOTIFY: NetworkConnectionStateChanged: Instant Tethering dccbd (/service/81): idle
NetworkEvent[16:01:35] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkError[16:01:35] NetworkPropertyUpdated: Instant Tethering dccbd.Error = "Unknown"
NetworkEvent[16:01:35] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkError[16:01:35] Connect Failed: connect-failed: 3758171b-52e3-4a3d-9af9-8457bf46512b
NetworkError[16:01:35] Connect Failure: connect-failed: /service/81
NetworkEvent[16:01:35] RequestUpdate: /service/81
NetworkEvent[16:01:35] NOTIFY: NetworkConnectionStateChanged: Instant Tethering dccbd (/service/81): failure
NetworkEvent[16:01:35] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkError[16:01:35] NetworkPropertyUpdated: Instant Tethering dccbd.Error = "out-of-range"
NetworkUser[16:01:33] GetShillProperties: /service/52
NetworkEvent[16:01:33] NOTIFY:NetworkListChanged: Size:30
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkEvent[16:01:33] NetworkPropertiesUpdated: Instant Tethering dccbd (/service/81)
NetworkEvent[16:01:33] InitialPropertiesReceived: /service/81: Instant Tethering dccbd State: configuration Visible: 1
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkEvent[16:01:33] RequestUpdate: /service/81
NetworkEvent[16:01:33] NOTIFY: NetworkConnectionStateChanged: Instant Tethering dccbd (/service/81): configuration
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/15
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/14
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/85
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/77
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:33] NetworkPropertyUpdated: SambaNetwork.WiFi.BSSID = "a4:2b:b0:c6:d1:45"
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:33] NetworkPropertyUpdated: SambaNetwork.WiFi.Frequency = 2412
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/8
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/7
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/6
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/9
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/5
NetworkUser[16:01:33] GetShillProperties: /service/52
NetworkEvent[16:01:33] NOTIFY:NetworkListChanged: Size:30
NetworkUser[16:01:33] GetShillProperties: /service/52
NetworkEvent[16:01:33] NOTIFY:NetworkListChanged: Size:30
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkEvent[16:01:33] NetworkPropertyUpdated: Instant Tethering dccbd.WiFi.BSSID = "02:1a:11:f7:7e:24"
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkEvent[16:01:33] NetworkPropertyUpdated: Instant Tethering dccbd.WiFi.Frequency = 2437
NetworkEvent[16:01:33] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkEvent[16:01:33] NetworkPropertyUpdated: Instant Tethering dccbd.Device = "/device/wlan0"
NetworkUser[16:01:31] GetShillProperties: /service/52
NetworkEvent[16:01:31] NOTIFY:NetworkListChanged: Size:30
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/92
NetworkEvent[16:01:31] NetworkPropertiesUpdated: Mozart (/service/92)
NetworkEvent[16:01:31] NOTIFY: NetworkConnectionStateChanged: Mozart (/service/92): idle
NetworkEvent[16:01:31] InitialPropertiesReceived: /service/92: Mozart State: idle Visible: 1
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/91
NetworkEvent[16:01:31] NetworkPropertiesUpdated: WIFI780FAB (/service/91)
NetworkEvent[16:01:31] NOTIFY: NetworkConnectionStateChanged: WIFI780FAB (/service/91): idle
NetworkEvent[16:01:31] InitialPropertiesReceived: /service/91: WIFI780FAB State: idle Visible: 1
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/89
NetworkEvent[16:01:31] NetworkPropertiesUpdated: CASTEEZ (/service/89)
NetworkEvent[16:01:31] NOTIFY: NetworkConnectionStateChanged: CASTEEZ (/service/89): idle
NetworkEvent[16:01:31] InitialPropertiesReceived: /service/89: CASTEEZ State: idle Visible: 1
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/90
NetworkEvent[16:01:31] NetworkPropertiesUpdated: NoPayNoPlay (/service/90)
NetworkEvent[16:01:31] NOTIFY: NetworkConnectionStateChanged: NoPayNoPlay (/service/90): idle
NetworkEvent[16:01:31] InitialPropertiesReceived: /service/90: NoPayNoPlay State: idle Visible: 1
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/88
NetworkEvent[16:01:31] NetworkPropertiesUpdated: Rose Cafe (/service/88)
NetworkEvent[16:01:31] NOTIFY: NetworkConnectionStateChanged: Rose Cafe (/service/88): idle
NetworkEvent[16:01:31] InitialPropertiesReceived: /service/88: Rose Cafe State: idle Visible: 1
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/87
NetworkEvent[16:01:31] NetworkPropertiesUpdated: DG1670A52 (/service/87)
NetworkEvent[16:01:31] NOTIFY: NetworkConnectionStateChanged: DG1670A52 (/service/87): idle
NetworkEvent[16:01:31] InitialPropertiesReceived: /service/87: DG1670A52 State: idle Visible: 1
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/15
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/14
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/77
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/82
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/67
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/67
NetworkEvent[16:01:31] NetworkPropertyUpdated: TWCWiFi.WiFi.BSSID = "c0:8a:de:3b:7b:b8"
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/67
NetworkEvent[16:01:31] NetworkPropertyUpdated: TWCWiFi.WiFi.Frequency = 2422
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/76
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/76
NetworkEvent[16:01:31] NetworkPropertyUpdated: CableWiFi.WiFi.BSSID = "c0:8a:de:7b:7b:b8"
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/76
NetworkEvent[16:01:31] NetworkPropertyUpdated: CableWiFi.WiFi.Frequency = 2422
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/64
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/64
NetworkEvent[16:01:31] NetworkPropertyUpdated: TWCWiFi-Passpoint.WiFi.BSSID = "c0:8a:de:bb:7b:b8"
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/64
NetworkEvent[16:01:31] NetworkPropertyUpdated: TWCWiFi-Passpoint.WiFi.Frequency = 2422
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:31] NetworkPropertyUpdated: SambaNetwork.WiFi.BSSID = "38:d5:47:db:ad:28"
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:31] NetworkPropertyUpdated: SambaNetwork.WiFi.Frequency = 2452
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/9
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/8
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/7
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/6
NetworkEvent[16:01:31] NetworkingPrivate.NetworkPropertiesUpdated: /service/5
NetworkUser[16:01:31] GetShillProperties: /service/52
NetworkEvent[16:01:31] NOTIFY:NetworkListChanged: Size:24
BluetoothEvent[16:01:28] Removing remote GATT service with UUID: 'b3b7e28e-a000-3e17-bd86-6e97b9e28c11' from device: 4E:C2:A9:4E:BE:B8
BluetoothEvent[16:01:28] Removing remote GATT service with UUID: '16bcfd00-253f-c348-e831-0db3e334d580' from device: 4E:C2:A9:4E:BE:B8
BluetoothEvent[16:01:28] Removing remote GATT service with UUID: '00001801-0000-1000-8000-00805f9b34fb' from device: 4E:C2:A9:4E:BE:B8
BluetoothEvent[16:01:28] /org/bluez/hci0/dev_4E_C2_A9_4E_BE_B8: Disconnected
NetworkUser[16:01:28] GetShillProperties: /service/52
NetworkEvent[16:01:28] NOTIFY:NetworkListChanged: Size:24
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/86
NetworkEvent[16:01:28] NetworkPropertiesUpdated: ARC2 (/service/86)
NetworkEvent[16:01:28] NOTIFY: NetworkConnectionStateChanged: ARC2 (/service/86): idle
NetworkEvent[16:01:28] InitialPropertiesReceived: /service/86: ARC2 State: idle Visible: 1
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/85
NetworkEvent[16:01:28] NetworkPropertiesUpdated: Miguelito (/service/85)
NetworkEvent[16:01:28] NOTIFY: NetworkConnectionStateChanged: Miguelito (/service/85): idle
NetworkEvent[16:01:28] InitialPropertiesReceived: /service/85: Miguelito State: idle Visible: 1
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/84
NetworkEvent[16:01:28] NetworkPropertiesUpdated: TG1672G52 (/service/84)
NetworkEvent[16:01:28] NOTIFY: NetworkConnectionStateChanged: TG1672G52 (/service/84): idle
NetworkEvent[16:01:28] InitialPropertiesReceived: /service/84: TG1672G52 State: idle Visible: 1
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/83
NetworkEvent[16:01:28] NetworkPropertiesUpdated: SambaAndroid5G (/service/83)
NetworkEvent[16:01:28] NOTIFY: NetworkConnectionStateChanged: SambaAndroid5G (/service/83): idle
NetworkEvent[16:01:28] InitialPropertiesReceived: /service/83: SambaAndroid5G State: idle Visible: 1
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/82
NetworkEvent[16:01:28] NetworkPropertiesUpdated: 0024b100000223d5 (/service/82)
NetworkEvent[16:01:28] NOTIFY: NetworkConnectionStateChanged: 0024b100000223d5 (/service/82): idle
NetworkEvent[16:01:28] InitialPropertiesReceived: /service/82: 0024b100000223d5 State: idle Visible: 1
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/15
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/14
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/77
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/44
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/1
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/4
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/2
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/8
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/7
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/6
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/68
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/9
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/5
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/9
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/9
NetworkEvent[16:01:28] NetworkPropertyUpdated: RADIUS-Team-Test.WiFi.BSSID = "04:bd:88:3d:fc:c6"
NetworkEvent[16:01:28] NetworkingPrivate.NetworkPropertiesUpdated: /service/9
NetworkEvent[16:01:28] NetworkPropertyUpdated: RADIUS-Team-Test.WiFi.Frequency = 2462
NetworkUser[16:01:27] GetShillProperties: /service/52
NetworkEvent[16:01:27] NOTIFY:NetworkListChanged: Size:19
NetworkEvent[16:01:27] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkEvent[16:01:27] NetworkPropertiesUpdated: Instant Tethering dccbd (/service/81)
NetworkEvent[16:01:27] InitialPropertiesReceived: /service/81: Instant Tethering dccbd State: association Visible: 1
NetworkUser[16:01:27] GetShillProperties: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkUser[16:01:27] GetManagedProperties: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkEvent[16:01:27] Connect Request Acknowledged: /service/81
NetworkEvent[16:01:27] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkEvent[16:01:27] RequestUpdate: /service/81
NetworkEvent[16:01:27] NOTIFY: NetworkConnectionStateChanged: Instant Tethering dccbd (/service/81): association
NetworkEvent[16:01:27] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkUser[16:01:26] GetShillProperties: /service/52
NetworkUser[16:01:26] Configure Succeeded: 3758171b-52e3-4a3d-9af9-8457bf46512b
NetworkEvent[16:01:26] NOTIFY:NetworkListChanged: Size:19
NetworkEvent[16:01:26] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkEvent[16:01:26] Sending Connect Request to Shill: /service/81
NetworkUser[16:01:26] ConnectToNetwork: /service/81
NetworkUser[16:01:26] ConnectToNetwork: 3758171b-52e3-4a3d-9af9-8457bf46512b
NetworkEvent[16:01:26] NetworkingPrivate.NetworkPropertiesUpdated: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkEvent[16:01:26] NetworkingPrivate.NetworkPropertiesUpdated: /service/81
NetworkEvent[16:01:26] NetworkPropertiesUpdated: Instant Tethering dccbd (/service/81)
NetworkEvent[16:01:26] InitialPropertiesReceived: /service/81: Instant Tethering dccbd State: idle Visible: 0
NetworkEvent[16:01:26] RequestUpdate: /service/81
NetworkUser[16:01:26] GetShillProperties: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkUser[16:01:26] GetManagedProperties: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkUser[16:01:26] Configure: wifi.WiFi.HexSSID="496E7374616E7420546574686572696E67206463636264"
NetworkUser[16:01:26] Configure: wifi.Type="wifi"
NetworkUser[16:01:26] Configure: wifi.SecurityClass="psk"
NetworkUser[16:01:26] Configure: wifi.SaveCredentials=true
NetworkUser[16:01:26] Configure: wifi.Profile="/profile/chronos/shill"
NetworkUser[16:01:26] Configure: wifi.Passphrase=******
NetworkUser[16:01:26] Configure: wifi.GUID="3758171b-52e3-4a3d-9af9-8457bf46512b"
NetworkUser[16:01:26] Configure: wifi.AutoConnect=false
NetworkUser[16:01:26] CreateShillConfiguration: wifi: 3758171b-52e3-4a3d-9af9-8457bf46512b
NetworkUser[16:01:26] CreateConfiguration
BluetoothEvent[16:01:26] /org/bluez/hci0/dev_4E_C2_A9_4E_BE_B8: Disconnecting
NetworkEvent[16:01:26] NetworkingPrivate.NetworkPropertiesUpdated: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
BluetoothEvent[16:01:26] Adding new remote GATT service for device: 4E:C2:A9:4E:BE:B8
BluetoothEvent[16:01:26] Adding new remote GATT service for device: 4E:C2:A9:4E:BE:B8
BluetoothEvent[16:01:26] Adding new remote GATT service for device: 4E:C2:A9:4E:BE:B8
BluetoothEvent[16:01:25] /org/bluez/hci0/dev_4E_C2_A9_4E_BE_B8: Connected, 0 still in progress
BluetoothEvent[16:01:25] Discovering changed: 0
BluetoothEvent[16:01:25] /org/bluez/hci0/dev_4E_C2_A9_4E_BE_B8: Connecting
BluetoothEvent[16:01:25] /org/bluez/hci0/dev_4E_C2_A9_4E_BE_B8: Connecting, 1 in progress
BluetoothEvent[16:01:25] OnStopDiscovery
BluetoothEvent[16:01:25] RemoveDiscoverySession
BluetoothEvent[16:01:25] Setting LE connection parameters: min=6, max=6
BluetoothEvent[16:01:25] Discovering changed: 1
NetworkUser[16:01:24] GetShillProperties: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkUser[16:01:24] GetManagedProperties: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
BluetoothEvent[16:01:24] OnStartDiscovery
BluetoothEvent[16:01:24] OnSetDiscoveryFilter
BluetoothEvent[16:01:24] AddDiscoverySession
NetworkEvent[16:01:24] NetworkingPrivate.NetworkPropertiesUpdated: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkEvent[16:01:24] NOTIFY: NetworkConnectionStateChanged: Huawei Nexus 6P (CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==): configuration
NetworkEvent[16:01:24] Default network is not connected: Huawei Nexus 6P (CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==)State: configuration
NetworkEvent[16:01:24] Changing connection state for Tether network with GUID CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==. Old state: , New state: configuration
NetworkEvent[16:01:24] Connecting to Tether network when there is currently no default network; setting as new default network. GUID: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkUser[16:01:24] ConnectToNetwork: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkUser[16:01:21] GetShillProperties: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkUser[16:01:21] GetManagedProperties: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkUser[16:01:21] GetShillProperties: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==
NetworkUser[16:01:21] GetManagedProperties: CAESRQohAPhXYHSwTHF8rofMvN7T6fftk8tZLhlBYfdXbyqQ5mBVEiAshgptGBlsEf3xyFy1t7A49YGdYvUokq/vc93sIKC0nA==

Comment 1 by khorimoto@chromium.org, Aug 3 2017 

Most of the time, the connection *does* succeed, but after it does succeed, it eventually loses connection to the Wi-Fi hotspot with the same "out-of-range" error.

We haven't seen this issue on other devices - is this a Kefka issue?

Comment 2 by steve...@chromium.org, Aug 3 2017

Cc: cernekee@chromium.org
Status: Untriaged (was: Available)
Can you provide a feedback report and a link? That will include Shill and system logs which might help diagnose why the wifi connection is getting dropped. 

Nothing looks obviously out of the ordinary in the included UI log; as noted, the WiFi network (i.e. the tether device) appears to be going out of range.

+cernekee@ who should be able to help investigate things on the Shill end.

Comment 3 by cernekee@chromium.org, Aug 3 2017

Cc: kirtika@chromium.org
Also please use `wpa_debug excessive` to increase logging verbosity.

Is the Chromebook a client on a hotspot AP?  Is there anything unusual about the configuration?

Comment 4 by khorimoto@chromium.org, Aug 3 2017 

stevenjb@:
(1) We are attempting to get a feedback report for you.
(2) Re: "out of range" - the device was not actually out of range. It was about 6 inches away from the phone providing the hotspot.

cernekee@: Yes, it is a client of a hotspot AP, but there is nothing unusual. The hotspot works part of the time with the same configuration.

Comment 5 by khorimoto@chromium.org, Aug 21 2017

Labels: -Pri-2 -M-61 Pri-3
Apologies for the delay in getting debug information for you. We've been prioritizing other issues for now and will not be able to get to this bug for the M-61 release, especially since we have only observed this on one device. We'll revisit this issue when we have free cycles. Thanks!

Comment 6 by jonm...@google.com, Sep 27 

I saw something close to this today: https://listnr.corp.google.com/product/208/report/85689315894
proximity_auth_logs_2018-09-27T17_44_03.339Z.txt
55.2 KB View Download

Comment 7 by kirtika@google.com, Sep 27 

#6: How is the feedback report relevant to this bug? This is about wifi hotspot connection issues, whereas the feedback report is bluetooth magic tether. Wrong link?

Comment 8 by khorimoto@chromium.org, Sep 27 

Re: comment #7: Instant Tethering creates a connection to a Wi-Fi hotspot to give the Chromebook connectivity.

Comment 9 by marcuskoehler@chromium.org, Jan 15

Labels: Enterprise-Triaged
►

Sign in to add a comment