[G Pad 8.3 Original Android Development] pbgapski:
logs of wifi disconnection

Follows the log from a typical disconnection while the tablet was idle. The reconnection happened when I woke it. It covers only one event so I post it inline.

This is the AP side (openwrt 15.05):

Wed Feb 17 12:16:48 2016 daemon.info hostapd: wlan1: STA f8:a9:d0:6c:XX WPA: group key handshake completed (RSN)
Wed Feb 17 12:16:48 2016 daemon.info hostapd: wlan1: STA f8:a9:d0:6c:XX IEEE 802.11: disassociated
Wed Feb 17 12:16:49 2016 daemon.info hostapd: wlan1: STA f8:a9:d0:6c:XX IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Wed Feb 17 12:47:00 2016 daemon.info hostapd: wlan1: STA f8:a9:d0:6c:XX IEEE 802.11: authenticated
Wed Feb 17 12:47:00 2016 daemon.info hostapd: wlan1: STA f8:a9:d0:6c:XX IEEE 802.11: associated (aid 5)
Wed Feb 17 12:47:00 2016 daemon.info hostapd: wlan1: STA f8:a9:d0:6c:XX WPA: pairwise key handshake completed (RSN)

This is the v500 radio log:

02-17 12:02:12.474 1427 1427 D DCT : [0]onReceive: action=android.intent.action.SCREEN_ON
02-17 12:02:12.474 1427 1427 D DCT : [0]stopNetStatPoll
02-17 12:02:12.475 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:02:12.475 1427 1427 D DCT : [0]overall state is IDLE
02-17 12:02:12.475 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:05:20.434 1427 1427 D DCT : [0]onReceive: action=android.intent.action.SCREEN_OFF
02-17 12:05:20.434 1427 1427 D DCT : [0]stopNetStatPoll
02-17 12:05:20.434 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:05:20.434 1427 1427 D DCT : [0]overall state is IDLE
02-17 12:05:20.434 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:16:48.552 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:16:48.554 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=true
02-17 12:16:48.623 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:16:48.624 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:16:48.634 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:16:48.635 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:16:48.696 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:16:48.696 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:16:48.709 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:16:48.709 1427 1427 D DctController: [TNF -2]Cellular needs Network for NetworkRequest [ id=1, legacyType=-1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ]
02-17 12:16:48.709 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:16:48.709 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:16:48.710 1427 1427 D DctController: [TNF -2]Request not useable, pending request.
02-17 12:16:48.746 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:16:48.747 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:16:48.765 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:16:48.765 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:16:48.772 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:16:48.772 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:16:48.904 1427 1440 D SubscriptionController: [getActiveSubIdList] simInfoSet=[]
02-17 12:16:48.904 1427 1440 D SubscriptionController: [getActiveSubIdList] X subIdArr.length=0
02-17 12:16:48.906 1427 1441 D SubscriptionController: [getActiveSubIdList] simInfoSet=[]
02-17 12:16:48.906 1427 1441 D SubscriptionController: [getActiveSubIdList] X subIdArr.length=0
02-17 12:46:56.577 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:46:56.577 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:46:56.797 1427 1427 D DCT : [0]onReceive: action=android.intent.action.SCREEN_ON
02-17 12:46:56.797 1427 1427 D DCT : [0]stopNetStatPoll
02-17 12:46:56.797 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:46:56.797 1427 1427 D DCT : [0]overall state is IDLE
02-17 12:46:56.797 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:47:00.082 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:47:00.082 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:47:00.093 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:47:00.093 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:47:00.162 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:47:00.162 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:47:00.162 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:47:00.162 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:47:00.188 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:47:00.188 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:47:00.188 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:47:00.188 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:47:00.205 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:47:00.206 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=false
02-17 12:47:00.349 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:47:00.350 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=true
02-17 12:47:00.354 1427 1427 D DCT : [0]onReceive: action=android.net.wifi.STATE_CHANGE
02-17 12:47:00.355 1427 1427 D DCT : [0]NETWORK_STATE_CHANGED_ACTION: mIsWifiConnected=true
02-17 12:47:00.483 1427 1569 D SubscriptionController: [getActiveSubIdList] simInfoSet=[]
02-17 12:47:00.483 1427 1569 D SubscriptionController: [getActiveSubIdList] X subIdArr.length=0
02-17 12:47:00.484 1427 1440 D SubscriptionController: [getActiveSubIdList] simInfoSet=[]
02-17 12:47:00.485 1427 1440 D SubscriptionController: [getActiveSubIdList] X subIdArr.length=0
02-17 12:47:02.823 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:47:02.823 1427 1427 D DctController: [TNF -2]Cellular releasing Network for NetworkRequest [ id=1, legacyType=-1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ]
02-17 12:47:02.823 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:47:02.823 1427 1427 D DctController: [TNF -2]Sub Info has not been ready, remove request.
02-17 12:50:01.479 1427 1427 D DCT : [0]onReceive: action=android.intent.action.SCREEN_OFF
02-17 12:50:01.479 1427 1427 D DCT : [0]stopNetStatPoll
02-17 12:50:01.479 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:50:01.479 1427 1427 D DCT : [0]overall state is IDLE
02-17 12:50:01.479 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:52:51.614 1427 1427 D DCT : [0]onReceive: action=android.intent.action.SCREEN_ON
02-17 12:52:51.614 1427 1427 D DCT : [0]stopNetStatPoll
02-17 12:52:51.614 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:52:51.615 1427 1427 D DCT : [0]overall state is IDLE
02-17 12:52:51.615 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:53:01.866 1427 1427 D DCT : [0]onReceive: action=android.intent.action.SCREEN_OFF
02-17 12:53:01.866 1427 1427 D DCT : [0]stopNetStatPoll
02-17 12:53:01.866 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:53:01.866 1427 1427 D DCT : [0]overall state is IDLE
02-17 12:53:01.866 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:53:06.548 1427 1427 D DCT : [0]onReceive: action=android.intent.action.SCREEN_ON
02-17 12:53:06.548 1427 1427 D DCT : [0]stopNetStatPoll
02-17 12:53:06.548 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:53:06.548 1427 1427 D DCT : [0]overall state is IDLE
02-17 12:53:06.548 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:54:45.087 1427 1427 D DCT : [0]onReceive: action=android.intent.action.SCREEN_OFF
02-17 12:54:45.087 1427 1427 D DCT : [0]stopNetStatPoll
02-17 12:54:45.087 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0
02-17 12:54:45.087 1427 1427 D DCT : [0]overall state is IDLE
02-17 12:54:45.087 1427 1427 D SubscriptionController: [getSubId]- sSlotIdxToSubId.size == 0, return DummySubIds slotIdx=0

And this comes from the next disconnection event:

[ 1125.650238 / 02-17 12:52:51.350] wlan: [860:E :SME] sme_QosPmcDeviceStateUpdateInd: 7237: nothing to process in PMC state 6
[ 1125.650360 / 02-17 12:52:51.360] wlan: [881:E :HDD] hdd_conf_arp_offload: 920: fenable = 0
[ 1136.148132 / 02-17 12:53:01.849] wlan: [860:E :SME] sme_QosPmcDeviceStateUpdateInd: 7237: nothing to process in PMC state 6
[ 1136.148193 / 02-17 12:53:01.849] wlan: [881:E :HDD] hdd_conf_arp_offload: 920: fenable = 1
[ 1140.777038 / 02-17 12:53:06.474] wlan: [860:E :SME] sme_QosPmcDeviceStateUpdateInd: 7237: nothing to process in PMC state 6
[ 1140.777099 / 02-17 12:53:06.474] wlan: [881:E :HDD] hdd_conf_arp_offload: 920: fenable = 0
[ 1162.620941 / 02-17 12:53:28.325] wlan: [881:E :HDD] wlan_hdd_get_station_stats: SME timeout while retrieving statistics
[ 1202.600860 / 02-17 12:54:08.304] wlan: [881:E :HDD] wlan_hdd_get_station_stats: SME timeout while retrieving statistics
[ 1239.385162 / 02-17 12:54:45.090] wlan: [860:E :SME] sme_QosPmcDeviceStateUpdateInd: 7237: nothing to process in PMC state 6
[ 1239.385223 / 02-17 12:54:45.090] wlan: [881:E :HDD] hdd_conf_arp_offload: 920: fenable = 1
[ 1289.253814 / 02-17 12:55:34.959] wlan: [860:E :SME] sme_QosPmcDeviceStateUpdateInd: 7237: nothing to process in PMC state 6
[ 1289.253967 / 02-17 12:55:34.959] wlan: [881:E :HDD] hdd_conf_arp_offload: 920: fenable = 0
[ 1361.635498 / 02-17 12:56:47.339] wlan: [881:E :SME] sme_FTUpdateKey: Unhandled state=0
[ 1378.427001 / 02-17 12:57:04.126] wlan: [860:E :SME] sme_QosPmcDeviceStateUpdateInd: 7237: nothing to process in PMC state 6
[ 1378.427062 / 02-17 12:57:04.126] wlan: [881:E :HDD] hdd_conf_arp_offload: 920: fenable = 1
[ 1962.211578 / 02-17 13:06:47.915] wlan: [881:E :SME] sme_FTUpdateKey: Unhandled state=0
[ 1962.223144 / 02-17 13:06:47.925] wlan: [881:E :SME] sme_FTUpdateKey: Unhandled state=0
[ 1962.290283 / 02-17 13:06:47.985] wlan: [860:E :TL ] ----> CRegion 0, hRSSI -68, Alpha 5
[ 1962.290374 / 02-17 13:06:47.985] wlan: [860:E :TL ] ----> CRegion 0, hRSSI -68, Alpha 5
[ 1962.291870 / 02-17 13:06:47.995] wlan: [860:E :PE ] limSendDisassocCnf: 3535: FT Preauth Session (e5238000,0) Cleanup
[ 1962.304382 / 02-17 13:06:48.005] wlan: disconnected
[ 1962.304443 / 02-17 13:06:48.005] wlan(0) 00:00:00:00:00:00 Standalone
[ 1963.456787 / 02-17 13:06:49.157] wlan: [881:E :HDD] wlan_hdd_cfg80211_sched_scan_start: aborting the existing scan is unsuccessfull

This may be an openwrt issue:

https://dev.openwrt.org/ticket/16623

https://forum.openwrt.org/viewtopic.php?id=43188