search

waleedAhmad1 / google-glass-api

Automatically exported from code.google.com/p/google-glass-api
0 stars 0 forks source link

Wifi disconnects in XE20.1 and won't reconnect until reboot // get mixed-up with Bluetooth #597

Open GoogleCodeExporter opened 9 years ago

GoogleCodeExporter commented 9 years ago 
What steps will reproduce the problem?
1. Configure Glass with Wifi and Bluetooth connection to an Android phone.
2. Disable bluetooth on the phone
3. Use Glass for a few minutes, going back-and-forth between main screen and 
settings => wifi disconnects. 

Impossible to reconnect, event to 2 different wifi networks (WPA and WPA2).
Then just reboot Glass, and everything works fine again, for a few minutes.

Note: if I turn ON the Bluetooth on my phone, the Wifi reconnects

What version of the product are you using? On what operating system?
XE20.1

Here are the relevant logs found:

08-26 19:46:36.615: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: 20
08-26 19:46:39.630: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: 20
08-26 19:46:39.927: I/PingRequester(708): Schedule ping to Eric Amram (Galaxy 
S4) @ [F0:6B:CA:BF:32:44] BONDED in 300000ms.
08-26 19:46:39.927: I/PairingPolicy/pairingReceiver(708): ACTION_UUID: Eric 
Amram (Galaxy S4) @ [F0:6B:CA:BF:32:44] BONDED
08-26 19:46:39.927: I/PairingPolicy/pairingReceiver(708):  we aren't supposed 
to be scanning...
08-26 19:46:39.935: V/FormattingLoggers(708): TimingData [count=60, 
sinceCreation=664530ms, spentLogging=180ms].
08-26 19:46:40.154: D/dalvikvm(796): GC_CONCURRENT freed 391K, 8% free 
6261K/6776K, paused 4ms+2ms, total 37ms
08-26 19:46:40.802: I/wpa_supplicant(571): wlan0: CTRL-EVENT-DISCONNECTED 
bssid=e8:fc:af:97:fc:1b reason=7
08-26 19:46:40.810: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: -67
08-26 19:46:40.810: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: -67
08-26 19:46:40.810: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: -67
08-26 19:46:40.810: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: -67
08-26 19:46:40.810: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: -2
08-26 19:46:40.810: I/wpa_supplicant(571): wlan0: Trying to associate with 
e8:fc:af:97:fc:1b (SSID='DoodHome' freq=2432 MHz)
08-26 19:46:40.927: I/wpa_supplicant(571): nl80211: 
wpa_driver_nl80211_event_receive->nl_recvmsgs failed: -11
08-26 19:46:40.927: D/CommandListener(120): Clearing all IP addresses on wlan0
08-26 19:46:40.958: D/CommandListener(120): Clearing all IP addresses on wlan0
08-26 19:46:40.958: D/ConnectivityService(527): ConnectivityChange for WIFI: 
DISCONNECTED/DISCONNECTED
08-26 19:46:40.958: D/ConnectivityService(527): tryFailover: set 
mActiveDefaultNetwork=-1, prevNetType=1
08-26 19:46:40.958: D/ConnectivityService(527): handleConnectivityChange: 
netType=1 doReset=true resetMask=3
08-26 19:46:40.958: D/ConnectivityService(527): handleConnectivityChange: 
changed linkProperty[1]: doReset=true resetMask=3
08-26 19:46:40.958: D/ConnectivityService(527):   curLp={InterfaceName: wlan0 
LinkAddresses: [10.0.0.8/24,fe80::fa8f:caff:fe24:f15f/64,]  Routes: 
[10.0.0.0/24 -> 0.0.0.0,0.0.0.0/0 -> 10.0.0.1,] DnsAddresses: [10.0.0.1,] 
Domains: MTU: 0HttpProxy: [ProxyProperties.mHost == null] }
08-26 19:46:40.958: D/ConnectivityService(527):   newLp= null
08-26 19:46:40.958: D/ConnectivityService(527): updateRoutes: default remove 
route r=10.0.0.0/24 -> 0.0.0.0
08-26 19:46:40.958: D/ConnectivityService(527): Removing 10.0.0.0/24 -> 0.0.0.0 
for interface wlan0
08-26 19:46:40.966: D/NetUtils(527): android_net_utils_resetConnections in 
env=0x59d0fbb8 clazz=0x55b00001 iface=wlan0 mask=0x3
08-26 19:46:40.966: E/ConnectivityService(527): Exception trying to remove a 
route: java.lang.IllegalStateException: command '27 interface route remove 
wlan0 default 10.0.0.0 24 0.0.0.0' failed with '400 27 Failed to remove route 
from default table (No such process)'
08-26 19:46:40.966: D/ConnectivityService(527): updateRoutes: default remove 
route r=0.0.0.0/0 -> 10.0.0.1
08-26 19:46:40.966: D/ConnectivityService(527): Removing 10.0.0.1/32 -> 0.0.0.0 
for interface wlan0
08-26 19:46:40.966: E/ConnectivityService(527): Exception trying to remove a 
route: java.lang.IllegalStateException: command '28 interface route remove 
wlan0 default 10.0.0.1 32 0.0.0.0' failed with '400 28 Failed to remove route 
from default table (No such process)'
08-26 19:46:40.966: D/ConnectivityService(527): Removing 0.0.0.0/0 -> 10.0.0.1 
for interface wlan0
08-26 19:46:40.966: E/ConnectivityService(527): Exception trying to remove a 
route: java.lang.IllegalStateException: command '29 interface route remove 
wlan0 default 0.0.0.0 0 10.0.0.1' failed with '400 29 Failed to remove route 
from default table (No such process)'
08-26 19:46:40.966: D/ConnectivityService(527): handleConnectivityChange: 
resetting
08-26 19:46:40.966: D/ConnectivityService(527): handleConnectivityChange: 
resetting curLp={InterfaceName: wlan0 LinkAddresses: 
[10.0.0.8/24,fe80::fa8f:caff:fe24:f15f/64,]  Routes: [10.0.0.0/24 -> 
0.0.0.0,0.0.0.0/0 -> 10.0.0.1,] DnsAddresses: [10.0.0.1,] Domains: MTU: 
0HttpProxy: [ProxyProperties.mHost == null] }
08-26 19:46:40.966: D/ConnectivityService(527): resetConnections(wlan0, 3)
08-26 19:46:41.021: D/ConnectivityService(527): reportNetworkCondition(-1, 0)
08-26 19:46:41.060: D/ConnectivityService(527): resetting DNS cache for wlan0
08-26 19:46:41.060: D/Nat464Xlat(527): requiresClat: netType=1, 
hasIPv4Address=false
08-26 19:46:41.060: D/ConnectivityService(527): sendStickyBroadcast: 
action=android.net.conn.CONNECTIVITY_CHANGE_IMMEDIATE
08-26 19:46:41.060: D/ConnectivityService(527): sendStickyBroadcastDelayed: 
delayMs=3000, action=android.net.conn.CONNECTIVITY_CHANGE
08-26 19:46:41.060: D/ConnectivityService(527): handleInetConditionChange: no 
active default network - ignore
08-26 19:46:41.919: I/wpa_supplicant(571): wlan0: CTRL-EVENT-ASSOC-REJECT 
bssid=e8:fc:af:97:fc:1b status_code=1
08-26 19:46:41.919: E/wpa_supplicant(571): Retrying assoc: 1 
08-26 19:46:41.919: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: -22
08-26 19:46:41.919: I/wpa_supplicant(571): wlan0: CTRL-EVENT-DISCONNECTED 
bssid=e8:fc:af:97:fc:1b reason=3 locally_generated=1
08-26 19:46:41.919: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: -67
08-26 19:46:41.919: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: -67
08-26 19:46:41.919: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: -67
08-26 19:46:41.919: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: -67
08-26 19:46:41.927: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: -2
08-26 19:46:41.935: I/wpa_supplicant(571): nl80211: 
wpa_driver_nl80211_event_receive->nl_recvmsgs failed: -11
08-26 19:46:42.029: I/wpa_supplicant(571): nl80211: 
wpa_driver_nl80211_event_receive->nl_recvmsgs failed: -11
08-26 19:46:43.240: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: 20
08-26 19:46:43.240: I/wpa_supplicant(571): nl80211: send_and_recv->nl_recvmsgs 
failed: 20
08-26 19:46:43.247: I/wpa_supplicant(571): nl80211: 
wpa_driver_nl80211_event_receive->nl_recvmsgs failed: -11
08-26 19:46:44.068: D/Tethering(527): MasterInitialState.processMessage what=3
08-26 19:46:44.068: D/CaptivePortalTracker(527): Disconnected from active 
network NetworkInfo: type: WIFI[], state: DISCONNECTED/DISCONNECTED, reason: 
(unspecified), extra: <unknown ssid>, roaming: false, failover: false, 
isAvailable: true, isConnectedToProvisioningNetwork: false
08-26 19:46:44.068: D/ConnectivityService(527): sendStickyBroadcast: 
action=android.net.conn.CONNECTIVITY_CHANGE
08-26 19:46:44.068: D/ConnectivityService(527): setProvNotificationVisible: E 
visible=false networkType=1 extraInfo=null url=null
08-26 19:46:44.076: I/CompanionSocketServer/connectivityReceiver(708): Received 
intent android.net.conn.CONNECTIVITY_CHANGE
08-26 19:46:44.083: I/CompanionSocketServer(708): isTethered = false, isOnWifi 
= false
08-26 19:46:44.083: I/NetworkSocketThread(708): Closing 
WifiNetworkSocketThread-WIFI_MULTICAST
08-26 19:46:44.083: I/CompanionServerThread(708): Unblock 
WifiNetworkSocketThread-WIFI_MULTICAST
08-26 19:46:44.091: I/ConnectivityChecker/receiver(708): Network is not 
connected.
08-26 19:46:44.091: I/TimelineSyncService(831): Caught wifi disconnection, 
notifying sync adapter to cancel opportunistic upload.
08-26 19:46:44.091: E/NetworkSocketThread(708): failed to listen for network 
client socket connect
08-26 19:46:44.091: E/NetworkSocketThread(708): java.net.SocketException: 
Socket closed
08-26 19:46:44.091: E/NetworkSocketThread(708):     at 
libcore.io.Posix.accept(Native Method)
08-26 19:46:44.091: E/NetworkSocketThread(708):     at 
libcore.io.BlockGuardOs.accept(BlockGuardOs.java:55)
08-26 19:46:44.091: E/NetworkSocketThread(708):     at 
java.net.PlainSocketImpl.accept(PlainSocketImpl.java:98)
08-26 19:46:44.091: E/NetworkSocketThread(708):     at 
java.net.ServerSocket.implAccept(ServerSocket.java:203)
08-26 19:46:44.091: E/NetworkSocketThread(708):     at 
java.net.ServerSocket.accept(ServerSocket.java:128)
08-26 19:46:44.091: E/NetworkSocketThread(708):     at 
com.google.glass.bluetooth.companion.NetworkSocketThread.runServer(NetworkSocket
Thread.java:197)
08-26 19:46:44.091: E/NetworkSocketThread(708):     at 
com.google.glass.bluetooth.companion.NetworkSocketThread.runInternally(NetworkSo
cketThread.java:262)
08-26 19:46:44.091: E/NetworkSocketThread(708):     at 
com.google.glass.bluetooth.companion.CompanionServerThread.run(CompanionServerTh
read.java:86)
08-26 19:46:44.107: I/SystemUpdateService(720): receiver: Intent { 
act=android.net.conn.CONNECTIVITY_CHANGE flg=0x4000010 
cmp=com.google.android.gms/.update.SystemUpdateService$ActiveReceiver (has 
extras) }
08-26 19:46:44.146: D/GCM(948): GcmService start Intent { 
act=android.net.conn.CONNECTIVITY_CHANGE flg=0x4000010 
cmp=com.google.android.gms/.gcm.GcmService (has extras) } 
android.net.conn.CONNECTIVITY_CHANGE
08-26 19:46:44.169: W/bt-sdp(777): SDP - Rcvd conn cnf with error: 0x4  CID 0x4a
08-26 19:46:44.169: D/GCM(948): GcmService start Intent { 
act=com.google.android.checkin.CHECKIN_COMPLETE flg=0x10 
cmp=com.google.android.gms/.gcm.GcmService (has extras) } 
com.google.android.checkin.CHECKIN_COMPLETE
08-26 19:46:44.185: I/RaftLogsReceiver(1149): Toggling raft logs [enabled=false]
08-26 19:46:44.201: D/dalvikvm(948): GC_CONCURRENT freed 396K, 8% free 
5569K/6008K, paused 2ms+3ms, total 48ms
08-26 19:46:44.247: W/NetworkUtil(708): Unable to reach 
https://www.google.com/generate_204: Unable to resolve host "www.google.com": 
No address associated with hostname
08-26 19:46:44.247: E/ConnectivityChecker(708): Ping failed
08-26 19:46:45.982: D/dalvikvm(796): GC_CONCURRENT freed 388K, 8% free 
6260K/6776K, paused 5ms+2ms, total 38ms
08-26 19:46:50.357: D/BluetoothPan(708): connect(F0:6B:CA:BF:32:44)
08-26 19:46:50.365: W/BluetoothAdapter(708): getBluetoothService() called with 
no BluetoothManagerCallback
08-26 19:46:50.365: D/BluetoothPanServiceJni(777): connectPanNative(L188): in
08-26 19:46:50.365: D/BTIF_SOCK(777): service_uuid: 
0000111f-0000-1000-8000-00805f9b34fb
08-26 19:46:50.372: D/BluetoothPanServiceJni(777): 
connection_state_callback(L72): state:1, local_role:2, remote_role:1
08-26 19:46:50.372: D/PanService(777): handlePanDeviceStateChange preState: 0 
state: 1
08-26 19:46:50.372: D/PanService(777): handlePanDeviceStateChange 
LOCAL_PANU_ROLE:REMOTE_NAP_ROLE state = 1, prevState = 0
08-26 19:46:50.372: D/PanService(777): Pan Device state : device: 
F0:6B:CA:BF:32:44 State:0->1
08-26 19:46:50.380: D/BluetoothSocket(708): connect(), SocketState: INIT, mPfd: 
{ParcelFileDescriptor: FileDescriptor[71]}
08-26 19:46:50.388: D/BluetoothAdapterProperties(777): CONNECTION_STATE_CHANGE: 
F0:6B:CA:BF:32:44: 0 -> 1
08-26 19:46:50.912: I/PowerManagerService(527): Going to sleep due to screen 
timeout...
08-26 19:46:51.154: D/SurfaceFlinger(122): Screen released, type=0 
flinger=0xb83d64d0
08-26 19:46:51.224: I/WindowManager(527): Screen turned off...
08-26 19:46:51.232: I/MainTimelineActivity[41bf5c18](807): onPause

and also:

08-26 19:19:15.780: I/wpa_supplicant(581): nl80211: send_and_recv->nl_recvmsgs 
failed: -16
08-26 19:19:15.780: W/wpa_supplicant(581): wlan0: Failed to initiate AP scan
08-26 19:19:15.835: I/WifiSelectorActivity[4213ba18](772): onCreate 
[savedInstanceState=null]
08-26 19:19:15.897: I/WifiHelper(772): Failed to start active wifi scan since 
the api is not found.  sdk version is 19

Please tell me if there is something else to do to help you diagnose.

Original issue reported on code.google.com by eric.am...@gmail.com on 27 Aug 2014 at 3:03