[AT&T, Rogers, Bell, Telus Samsung Galaxy S III] pogeymanz:
WiFi dropping during sleep issue

Hi Guys,

I'm wandering over here from the d2vzw area because I haven't gotten the help I need there.

I searched this thread and saw several people describe the issue I'm having: WiFi turns off during sleep even though I have it said to stay on "Always."

Is anybody here still having the issue or has this been resolved in d2att? Have those same fixes been ported to d2vzw? I assume they must have the same WiFi hardware/drivers. Nobody else in the d2vzw thread seems to be having the issue.

I've got some logs from right after waking up my phone with:

10-20 10:16:54.441 I/Timeline(14231): Timeline: Activity_idle id: android.os.BinderProxy@287ce329 time:65096897
10-20 10:16:55.124 I/ActivityManager(1561): START u0 {act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10200000 cmp=com.cyanogenmod.trebuchet/com.android.launcher3.Launcher} from uid 1000 on display 0
10-20 10:16:55.425 I/Timeline(2347): Timeline: Activity_idle id: android.os.BinderProxy@2c487c3c time:65097881
10-20 10:16:55.671 I/Timeline(1561): Timeline: Activity_windows_visible id: ActivityRecord{1711bd9f u0 com.cyanogenmod.trebuchet/com.android.launcher3.Launcher t776} time:65098127
10-20 10:16:55.919 W/OpenGLRenderer(2347): Incorrectly called buildLayer on View: ShortcutAndWidgetContainer, destroying layer...
10-20 10:16:56.142 E/WifiStateMachine(1561): WifiStateMachine shouldSwitchNetwork txSuccessRate=102815.12 rxSuccessRate=192874.25 delta 1000 -> 1
10-20 10:16:56.145 E/WifiStateMachine(1561): CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=0 roam=3
10-20 10:16:56.145 E/WifiStateMachine(1561): CMD_AUTO_CONNECT will save config -> "Taz" nid=0
10-20 10:16:56.146 E/wifi_gbk2utf(1561): g_pItemList is NULL
10-20 10:16:56.230 E/WifiConfigStore(1561): Setting BSSID for "Taz"-WPA_PSK to any
10-20 10:16:56.231 E/wifi_gbk2utf(1561): g_pItemList is NULL
10-20 10:16:56.233 E/wifi_gbk2utf(1561): g_pItemList is NULL
10-20 10:16:56.234 E/wifi_gbk2utf(1561): g_pItemList is NULL
10-20 10:16:56.235 E/wifi_gbk2utf(1561): g_pItemList is NULL
10-20 10:16:56.237 E/wifi_gbk2utf(1561): g_pItemList is NULL
10-20 10:16:56.238 E/wifi_gbk2utf(1561): g_pItemList is NULL
10-20 10:16:56.239 E/wifi_gbk2utf(1561): g_pItemList is NULL
10-20 10:16:56.240 E/WifiConfigStore(1561): will read network variables netId=0
10-20 10:16:56.263 W/ResourceType(1954): No package identifier when getting value for resource number 0x00000000
10-20 10:16:56.263 W/PackageManager(1954): Failure retrieving resources for com.android.calendar: Resource ID #0x0
10-20 10:16:56.275 E/WifiStateMachine(1561): CMD_AUTO_CONNECT did save config -> nid=0
10-20 10:16:56.276 E/wifi_gbk2utf(1561): g_pItemList is NULL
10-20 10:16:56.277 E/WifiConfigStore(1561): will read network variables netId=0
10-20 10:16:56.291 I/wpa_supplicant(1838): wlan0: Trying to associate with 00:22:6b:99:a6:10 (SSID='Taz' freq=2437 MHz)
10-20 10:16:56.354 D/WifiStateChangeService(2642): WiFi change service started, clearing info about wifi state until we have figured it out again.
10-20 10:16:56.365 D/WifiStateChangeService(2642): WiFi change service started, clearing info about wifi state until we have figured it out again.
10-20 10:16:56.394 I/wpa_supplicant(1838): wlan0: Associated with 00:22:6b:99:a6:10
10-20 10:16:56.394 I/wpa_supplicant(1838): wlan0: CTRL-EVENT-EAP-SUCCESS EAP authentication completed successfully (based on lower layer success)
10-20 10:16:56.425 D/WifiStateChangeService(2642): WiFi change service started, clearing info about wifi state until we have figured it out again.
10-20 10:16:56.435 I/wpa_supplicant(1838): wlan0: WPA: Key negotiation completed with 00:22:6b:99:a6:10 [PTK=CCMP GTK=CCMP]
10-20 10:16:56.435 I/wpa_supplicant(1838): wlan0: CTRL-EVENT-CONNECTED - Connection to 00:22:6b:99:a6:10 completed [id=0 id_str=]
10-20 10:16:56.436 D/WifiStateChangeService(2642): WiFi change service started, clearing info about wifi state until we have figured it out again.
10-20 10:16:56.458 D/ConnectivityService(1561): registerNetworkAgent NetworkAgentInfo{ ni{[type: WIFI[], state: CONNECTING/CONNECTING, reason: (unspecified), extra: "Taz", roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]} network{null} lp{{InterfaceName: wlan0 LinkAddresses: [fe80::9218:7cff:fe28:7416/64,] Routes: [] DnsAddresses: [] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}} nc{[ Transports: WIFI Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=1048576Kbps LinkDnBandwidth>=1048576Kbps]} Score{20} everValidated{false} lastValidated{false} created{false} explicitlySelected{false} }
10-20 10:16:56.458 E/WifiConfigStore(1561): saveWifiConfigBSSID Setting BSSID for "Taz"-WPA_PSK to any
10-20 10:16:56.459 E/wifi_gbk2utf(1561): g_pItemList is NULL
10-20 10:16:56.461 D/ConnectivityService(1561): NetworkAgentInfo [WIFI () - 266] EVENT_NETWORK_INFO_CHANGED, going from null to CONNECTING
10-20 10:16:56.462 D/WifiStateChangeService(2642): WiFi change service started, clearing info about wifi state until we have figured it out again.
10-20 10:16:56.464 E/WifiConfigStore(1561): saveWifiConfigBSSID Setting BSSID for "Taz"-WPA_PSK to any
10-20 10:16:56.464 E/wifi_gbk2utf(1561): g_pItemList is NULL
10-20 10:16:56.475 D/CommandListener(1281): Setting iface cfg
10-20 10:16:56.476 E/WifiStateMachine(1561): Start Dhcp Watchdog 234
10-20 10:16:56.476 D/WifiStateChangeService(2642): WiFi change service started, clearing info about wifi state until we have figured it out again.
10-20 10:16:56.487 D/WifiStateChangeService(2642): WiFi change service started, clearing info about wifi state until we have figured it out again.
10-20 10:16:56.497 D/ConnectivityService(1561): updateNetworkScore for NetworkAgentInfo [WIFI () - 266] to 60
10-20 10:16:56.605 E/wpa_supplicant(1838): wpa_driver_nl80211_driver_cmd: failed to issue private command: WLS_BATCHING GET
10-20 10:16:56.606 E/WifiStateMachine(1561): Unexpected BatchedScanResults :null
10-20 10:16:56.606 E/wpa_supplicant(1838): wpa_driver_nl80211_driver_cmd: failed to issue private command: WLS_BATCHING STOP
10-20 10:16:56.644 E/Sensors (1561): accelHandler -1.608256 6.490503 7.076085
10-20 10:16:56.849 I/dhcpcd (17513): version 5.5.6 starting
10-20 10:16:56.947 I/dhcpcd (17513): wlan0: rebinding lease of 192.168.1.110
10-20 10:16:56.974 I/dhcpcd (17513): wlan0: acknowledged 192.168.1.110 from 192.168.1.1
10-20 10:16:56.976 I/dhcpcd (17513): wlan0: leased 192.168.1.110 for 86400 seconds
10-20 10:16:57.229 W/ProcessCpuTracker(1561): Skipping unknown process pid 17522
10-20 10:16:57.320 W/OpenGLRenderer(2347): Incorrectly called buildLayer on View: OverviewPanel, destroying layer...
10-20 10:16:57.454 D/ConnectivityService(1561): NetworkAgentInfo [WIFI () - 266] EVENT_NETWORK_INFO_CHANGED, going from CONNECTING to CONNECTED
10-20 10:16:57.460 D/ConnectivityService(1561): Adding iface wlan0 to network 266
10-20 10:16:57.464 D/WifiStateChangeService(2642): WiFi change service started, clearing info about wifi state until we have figured it out again.
10-20 10:16:57.498 E/WifiStateMachine(1561): Did not find remoteAddress {192.168.1.1} in /proc/net/arp
10-20 10:16:57.501 D/WifiStateChangeService(2642): WiFi change service started, clearing info about wifi state until we have figured it out again.
10-20 10:16:57.526 D/WifiStateChangeService(2642): WiFi change service started, clearing info about wifi state until we have figured it out again.
10-20 10:16:57.533 E/ConnectivityService(1561): Unexpected mtu value: 0, wlan0
10-20 10:16:57.534 D/ConnectivityService(1561): Adding Route [fe80::/64 -> :: wlan0] to network 266
10-20 10:16:57.535 D/WifiStateChangeService(2642): WiFi change service started, clearing info about wifi state until we have figured it out again.
10-20 10:16:57.536 D/ConnectivityService(1561): Adding Route [192.168.1.0/24 -> 0.0.0.0 wlan0] to network 266
10-20 10:16:57.537 D/ConnectivityService(1561): Adding Route [0.0.0.0/0 -> 192.168.1.1 wlan0] to network 266
10-20 10:16:57.540 D/ConnectivityService(1561): Setting Dns servers for network 266 to [/192.168.1.1]
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642): Unable to configure a fingerprint or HTTPS for the local repo
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642): java.lang.InterruptedException
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642):	at java.lang.Thread.sleep(Native Method)
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642):	at java.lang.Thread.sleep(Thread.java:1031)
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642):	at java.lang.Thread.sleep(Thread.java:985)
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642):	at org.fdroid.fdroid.net.WifiStateChangeService$WaitForWifiAsyncTask.doInBackground(WifiStateChangeService.java:102)
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642):	at org.fdroid.fdroid.net.WifiStateChangeService$WaitForWifiAsyncTask.doInBackground(WifiStateChangeService.java:71)
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642):	at android.os.AsyncTask$2.call(AsyncTask.java:292)
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642):	at java.util.concurrent.FutureTask.run(FutureTask.java:237)
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642):	at android.os.AsyncTask$SerialExecutor$1.run(AsyncTask.java:231)
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642):	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1112)
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642):	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:587)
10-20 10:16:57.550 E/WaitForWifiAsyncTask(2642):	at java.lang.Thread.run(Thread.java:818)
10-20 10:16:57.551 D/WaitForWifiAsyncTask(2642): Checking wifi state (in background thread).
10-20 10:16:57.558 D/NetworkManagementService(1561): getNetworkStatsSummaryXt:NetworkStats: elapsedRealtime=520114503
10-20 10:16:57.558 D/NetworkManagementService(1561): [0] iface=rmnet1 uid=-1 set=ALL tag=0x0 rxBytes=1892276 rxPackets=2683 txBytes=1474867 txPackets=2879 operations=0
10-20 10:16:57.558 D/NetworkManagementService(1561): [1] iface=wlan0 uid=-1 set=ALL tag=0x0 rxBytes=1717014876 rxPackets=1542995 txBytes=59307985 txPackets=822524 operations=0
10-20 10:16:57.558 D/NetworkManagementService(1561): [2] iface=p2p0 uid=-1 set=ALL tag=0x0 rxBytes=0 rxPackets=0 txBytes=384 txPackets=6 operations=0
10-20 10:16:57.558 D/NetworkManagementService(1561): [3] iface=lo uid=-1 set=ALL tag=0x0 rxBytes=134110252 rxPackets=234147 txBytes=134110252 txPackets=234147 operations=0
10-20 10:16:57.559 D/WaitForWifiAsyncTask(2642): Have wifi info, connected to "Taz"
10-20 10:16:57.560 D/NetworkManagementService(1561): getNetworkStatsSummaryDev:NetworkStats: elapsedRealtime=520114505
10-20 10:16:57.560 D/NetworkManagementService(1561): [0] iface=rmnet1 uid=-1 set=ALL tag=0x0 rxBytes=1892276 rxPackets=2683 txBytes=1499232 txPackets=2878 operations=0
10-20 10:16:57.560 D/NetworkManagementService(1561): [1] iface=wlan0 uid=-1 set=ALL tag=0x0 rxBytes=1718415535 rxPackets=1568187 txBytes=135090726 txPackets=824819 operations=0
10-20 10:16:57.560 D/NetworkManagementService(1561): [2] iface=p2p0 uid=-1 set=ALL tag=0x0 rxBytes=0 rxPackets=0 txBytes=0 txPackets=0 operations=0
10-20 10:16:57.560 D/NetworkManagementService(1561): [3] iface=lo uid=-1 set=ALL tag=0x0 rxBytes=134110252 rxPackets=234147 txBytes=134110252 txPackets=234147 operations=0
10-20 10:16:57.568 D/ConnectivityService(1561): notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 266]
10-20 10:16:57.581 D/NetworkManagementService(1561): getNetworkStatsSummaryXt:NetworkStats: elapsedRealtime=520114526
10-20 10:16:57.581 D/NetworkManagementService(1561): [0] iface=rmnet1 uid=-1 set=ALL tag=0x0 rxBytes=1892276 rxPackets=2683 txBytes=1474867 txPackets=2879 operations=0
10-20 10:16:57.581 D/NetworkManagementService(1561): [1] iface=wlan0 uid=-1 set=ALL tag=0x0 rxBytes=1717014876 rxPackets=1542995 txBytes=59307985 txPackets=822524 operations=0
10-20 10:16:57.581 D/NetworkManagementService(1561): [2] iface=p2p0 uid=-1 set=ALL tag=0x0 rxBytes=0 rxPackets=0 txBytes=384 txPackets=6 operations=0
10-20 10:16:57.581 D/NetworkManagementService(1561): [3] iface=lo uid=-1 set=ALL tag=0x0 rxBytes=134110252 rxPackets=234147 txBytes=134110252 txPackets=234147 operations=0
10-20 10:16:57.583 D/NetworkManagementService(1561): getNetworkStatsSummaryDev:NetworkStats: elapsedRealtime=520114529
10-20 10:16:57.583 D/NetworkManagementService(1561): [0] iface=rmnet1 uid=-1 set=ALL tag=0x0 rxBytes=1892276 rxPackets=2683 txBytes=1499232 txPackets=2878 operations=0
10-20 10:16:57.583 D/NetworkManagementService(1561): [1] iface=wlan0 uid=-1 set=ALL tag=0x0 rxBytes=1718415535 rxPackets=1568187 txBytes=135090726 txPackets=824819 operations=0
10-20 10:16:57.583 D/NetworkManagementService(1561): [2] iface=p2p0 uid=-1 set=ALL tag=0x0 rxBytes=0 rxPackets=0 txBytes=0 txPackets=0 operations=0
10-20 10:16:57.583 D/NetworkManagementService(1561): [3] iface=lo uid=-1 set=ALL tag=0x0 rxBytes=134110252 rxPackets=234147 txBytes=134110252 txPackets=234147 operations=0
10-20 10:16:57.598 D/ConnectivityService(1561): notifyType PRECHECK for NetworkAgentInfo [WIFI () - 266]
10-20 10:16:57.599 D/NetworkMonitor/NetworkAgentInfo [WIFI () - 266]( 1561): DefaultState{ when=-1ms what=532481 target=com.android.internal.util.StateMachine$SmHandler }
10-20 10:16:57.599 D/NetworkMonitor/NetworkAgentInfo [WIFI () - 266]( 1561): Connected
10-20 10:16:57.599 D/NetworkMonitor/NetworkAgentInfo [WIFI () - 266]( 1561): EvaluatingState{ when=0 what=532486 arg1=1 target=com.android.internal.util.StateMachine$SmHandler }
10-20 10:16:57.599 D/NetworkMonitor/NetworkAgentInfo [WIFI () - 266]( 1561): Validated
10-20 10:16:57.599 D/ConnectivityService(1561): rematching NetworkAgentInfo [WIFI () - 266]
10-20 10:16:57.599 D/ConnectivityService(1561): accepting network in place of null
10-20 10:16:57.602 D/ConnectivityService(1561): Switching to new default network: NetworkAgentInfo{ ni{[type: WIFI[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: "Taz", roaming: false, failover: false, isAvailable: true, isConnectedToProvisioningNetwork: false]} network{266} lp{{InterfaceName: wlan0 LinkAddresses: [fe80::9218:7cff:fe28:7416/64,192.168.1.110/24,] Routes: [fe80::/64 -> :: wlan0,192.168.1.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.1.1 wlan0,] DnsAddresses: [192.168.1.1,] Domains: MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}} nc{[ Transports: WIFI Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=1048576Kbps LinkDnBandwidth>=1048576Kbps]} Score{20} everValidated{false} lastValidated{false} created{true} explicitlySelected{false} }
10-20 10:16:57.605 D/ConnectivityService(1561): notifyType AVAILABLE for NetworkAgentInfo [WIFI () - 266]
10-20 10:16:57.606 D/ConnectivityManager.CallbackHandler(1954): CM callback handler got msg 524290
10-20 10:16:57.607 D/CSLegacyTypeTracker(1561): Sending connected broadcast for type 1 NetworkAgentInfo [WIFI () - 266] isDefaultNetwork=true
10-20 10:16:57.607 D/ConnectivityService(1561): sendStickyBroadcast: action=android.net.conn.CONNECTIVITY_CHANGE_IMMEDIATE
10-20 10:16:57.610 D/Tethering(1561): MasterInitialState.processMessage what=3
10-20 10:16:57.610 D/ConnectivityService(1561): sendStickyBroadcast: action=android.net.conn.CONNECTIVITY_CHANGE
10-20 10:16:57.615 D/ConnectivityService(1561): notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 266]
10-20 10:16:57.619 D/ConnectivityService(1561): setProvNotificationVisibleIntent: E visible=false networkType=0 extraInfo=null
10-20 10:16:57.620 D/ConnectivityService(1561): Validated NetworkAgentInfo [WIFI () - 266]
10-20 10:16:57.620 D/ConnectivityService(1561): rematching NetworkAgentInfo [WIFI () - 266]
10-20 10:16:57.620 D/ConnectivityService(1561): Network NetworkAgentInfo [WIFI () - 266] was already satisfying request 1. No change.
10-20 10:16:57.620 D/ConnectivityManager.CallbackHandler(1954): CM callback handler got msg 524295
10-20 10:16:57.623 D/ConnectivityService(1561): notifyType AVAILABLE for NetworkAgentInfo [WIFI () - 266]
10-20 10:16:57.624 I/UpdateCheckReceiver(3058): Got connectivity change, has connection: true
10-20 10:16:57.624 I/UpdateCheckReceiver(3058): Scheduling future, repeating update checks.
10-20 10:16:57.630 D/ConnectivityManager.CallbackHandler(1954): CM callback handler got msg 524290
10-20 10:16:57.633 D/ConnectivityService(1561): sendStickyBroadcast: action=android.net.conn.INET_CONDITION_ACTION
10-20 10:16:57.660 I/UpdateService(2642): Skipping update - done 76605477ms ago, interval is 24 hours
10-20 10:16:57.691 D/ConnectivityManager(3220): getMobileDataEnabled()+ subId=1
10-20 10:16:57.693 D/PhoneInterfaceManager(2325): [PhoneIntfMgr] getDataEnabled: subId=1 phoneId=0
10-20 10:16:57.693 D/PhoneInterfaceManager(2325): [PhoneIntfMgr] getDataEnabled: subId=1 retVal=false
10-20 10:16:57.693 D/ConnectivityManager(3220): getMobileDataEnabled()- subId=1 retVal=false

Thanks in advance for any help.