Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Network

Moderators: odroid, mdrjr

Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Network

Unread postby montaigne » Sun Feb 04, 2018 12:51 pm

Images Tested: (same WiFi Issue, I am testing to find the last "working" version" | I will keep it updated as I test)
https://wiki.odroid.com/odroid-c2/os_im ... allow_v3.6
https://wiki.odroid.com/odroid-c2/os_im ... allow_v3.5
https://wiki.odroid.com/odroid-c2/os_im ... allow_v3.4
https://wiki.odroid.com/odroid-c2/os_im ... allow_v3.3
https://wiki.odroid.com/odroid-c2/os_im ... allow_v3.1
https://wiki.odroid.com/odroid-c2/os_im ... allow_v3.0
https://wiki.odroid.com/odroid-c2/os_im ... allow_v1.0

I haven't tested every version 6.0 images, but it appears to an issue with 6.0.1 images. Last image from 5.1.1 v3.5 works as expected.

Expected Behavior: WiFi automatically reconnects when the network is available.

Observed Behavior:
Module 0: Wifi never reconnects. If the unit is rebooted WiFi reconnects. If you manually choose to reconnect, it works.
Module 3: WiFi automatically reconnects as expected.

Module 0: http://www.hardkernel.com/main/products ... 5431421052
Module 3: http://www.hardkernel.com/main/products ... 7447734369

How to test:

Use any image from 6.0.1 listed above with module 0 wifi. Setup wifi on the unit. Turn wifi off (router or hotspot - NOT ODROID). Wait for wifi to go offline on c2. Reconnect wifi and watch as it never reconnects.
Repeat actions with module 3 wifi. Works as expected, when wifi is turned back on - Odroid automatically reconnects to the network.
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby montaigne » Tue Feb 06, 2018 12:33 pm

https://wiki.odroid.com/odroid-c2/os_im ... allow_v3.7

Also exhibits the same bug. Obviously, this was not reported in time to be fixed with that release but figured I would test to be sure
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby codewalker » Tue Feb 06, 2018 2:09 pm

I installed v3.7 version and WiFi #0 automatically reconnected.
Code: Select all
shell@odroidc2:/ $ uname -a
Linux localhost 3.14.29 #1 SMP PREEMPT Mon Feb 5 11:43:27 KST 2018 armv8l
shell@odroidc2:/ $ getprop ro.bootimage.build.fingerprint
ODROID/odroidc2/odroidc2:6.0.1/MOI10E/odroidc2-eng-s905_6.0.1_master-88:eng/test-keys
shell@odroidc2:/ $


Code: Select all
$ adb logcat | grep -i wifi
01-01 09:00:10.391  3201  3201 D lights.odroid: open_lights: wifi light isn't supported yet.
01-01 09:00:11.430  2871  2871 I AudioFlinger: loadHwModule() Loaded r_submix audio interface from Wifi Display audio HAL (audio) handle 5
01-01 09:00:11.799  3201  3201 W PackageParser: Ignoring duplicate uses-permissions/uses-permissions-sdk-m: android.permission.CONFIGURE_WIFI_DISPLAY in package: com.android.systemui at: Binary XML file line #120
01-01 09:00:12.345  3201  3201 W PackageManager: Permission com.google.android.googleapps.permission.GOOGLE_AUTH.wifi from package com.google.android.gsf in an unknown group android.permission-group.ACCOUNTS
01-01 09:00:17.905  3201  3201 I SystemServiceManager: Starting com.android.server.wifi.p2p.WifiP2pService
01-01 09:00:17.941  3201  3201 I WifiP2pService: Registering wifip2p
01-01 09:00:17.943  3201  3201 I SystemServiceManager: Starting com.android.server.wifi.WifiService
01-01 09:00:18.066  3201  3201 D WifiController: isAirplaneModeOn = false, isWifiEnabled = true, isScanningAvailable = false
01-01 09:00:18.068  3201  3201 I WifiService: Registering wifi
01-01 09:00:18.069  3201  3201 I SystemServiceManager: Starting com.android.server.wifi.WifiScanningService
01-01 09:00:18.069  3201  3201 I WifiScanningService: Creating wifiscanner
01-01 09:00:18.070  3201  3201 I WifiScanningService: Starting wifiscanner
01-01 09:00:18.071  3201  3201 I SystemServiceManager: Starting com.android.server.wifi.RttService
01-01 09:00:18.083  3201  3201 D ConnectivityService: wifiOnly=false
01-01 09:00:18.264  3201  3271 D WifiApConfigStore: 2G band allowed channels are:1,6,11
01-01 09:00:18.299  3201  3201 I WifiService: WifiService starting up with Wi-Fi enabled
01-01 09:00:18.303  3201  3201 D WifiService: setWifiEnabled: true pid=3201, uid=1000
01-01 09:00:18.303  3201  3201 E WifiService: Invoking mWifiStateMachine.setWifiEnabled
01-01 09:00:18.304  3201  3272 D WifiController: WifiController msg { when=0 what=155656 target=com.android.internal.util.StateMachine$SmHandler } deferred for 500ms
01-01 09:00:18.307  3201  3272 D WifiService: New client listening to asynchronous messages
01-01 09:00:18.309  3201  3201 I WifiScanningService: Registering wifiscanner
01-01 09:00:18.810  3201  3272 D WifiController: DEFERRED_TOGGLE handled
01-01 09:00:18.823  3201  3271 I WifiHW  : USB WiFi device is detected, [148f:5370]
01-01 09:00:18.824  3201  3271 I WifiHW  : Loading /system/lib/modules/backports/compat.ko
01-01 09:00:19.047  3201  3271 I WifiHW  : Loading /system/lib/modules/backports/cfg80211.ko
01-01 09:00:19.592  3201  3271 I WifiHW  : Loading /system/lib/modules/backports/mac80211.ko
01-01 09:00:20.063  3201  3271 I WifiHW  : Loading /system/lib/modules/backports/rt2x00lib.ko
01-01 09:00:20.303  3201  3271 I WifiHW  : Loading /system/lib/modules/backports/rt2x00usb.ko
01-01 09:00:20.328  3201  3272 D WifiService: New client listening to asynchronous messages
01-01 09:00:20.512  3201  3271 I WifiHW  : Loading /system/lib/modules/backports/rt2800lib.ko
01-01 09:00:20.728  3201  3271 I WifiHW  : Loading /system/lib/modules/backports/rt2800usb.ko
01-01 09:00:21.212  3201  3271 D WifiHW  : check loading wifi driver is ok...
01-01 09:00:21.212  3201  3271 E WifiHW  : driver loaded
01-01 09:00:21.225  3201  3271 E WifiMonitor: killSupplicant p2pfalse init.svc.wpa_supplicant=unknown init.svc.p2p_supplicant=unknown
01-01 09:00:21.326  3201  3271 D wifi    : Can not initialize the vendor function pointer table
01-01 09:00:21.326  3201  3271 E WifiNative-HAL: Could not start hal
01-01 09:00:21.326  3201  3271 E WifiStateMachine: Failed to start HAL
01-01 09:00:21.453  3201  3271 D WifiMonitor: startMonitoring(wlan0) with mConnected = false
01-01 09:00:22.049  3201  3271 D WifiConfigStore: Loading config and enabling all networks
01-01 09:00:22.078  3201  3271 D WifiConfigStore: loaded 0 passpoint configs
01-01 09:00:22.078  3201  3271 E WifiConfigStore: Error parsing configuration: java.io.FileNotFoundException: /data/misc/wifi/ipconfig.txt: open failed: ENOENT (No such file or directory)
01-01 09:00:22.126  3201  3271 W WifiConfigStore: Upgrading network 1 to android.uid.system:1000
01-01 09:00:22.128  3201  3271 W WifiConfigStore: Upgrading network 0 to android.uid.system:1000
01-01 09:00:22.129  3201  3271 E WifiConfigStore: readAutoJoinStatus: Error parsing configurationjava.io.FileNotFoundException: /data/misc/wifi/autojoinconfig.txt: open failed: ENOENT (No such file or directory)
01-01 09:00:22.130  3201  3271 E WifiConfigStore: buildPnoList sortedWifiConfigurations size 2
01-01 09:00:22.130  3201  3271 E WifiConfigStore: found sortedWifiConfigurations : "HK-N750-CHRIS-5G"WPA_PSK
01-01 09:00:22.130  3201  3271 E WifiConfigStore: found sortedWifiConfigurations : "HK-N750-CHRIS-2.4G"WPA_PSK
01-01 09:00:22.143  3201  3271 D WifiNative-HAL: Setting external_sim to 1
01-01 09:00:22.144  3201  3271 D WifiStateMachine: Setting OUI to DA-A1-19
01-01 09:00:22.151  3201  3271 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 09:00:22.156  3201  3271 E WifiStateMachine: setScanAlarm after 3 sec.
01-01 09:00:22.157  3201  3271 D WifiNative-HAL: Failing getSupportedFeatureset because HAL isn't started
01-01 09:00:22.158  3201  3286 E WifiScanningService: could not start HAL
01-01 09:00:22.166  3201  3272 D WifiService: New client listening to asynchronous messages
01-01 09:00:22.177  3201  3271 D WifiConfigStore: No blacklist allowed without epno enabled
01-01 09:00:22.177  3201  3271 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 09:00:22.510  3201  3272 D WifiService: New client listening to asynchronous messages
01-01 09:00:23.664  3201  3271 D WIFI    : Registering NetworkFactory
01-01 09:00:23.665  3201  3271 D WIFI_UT : Registering NetworkFactory
01-01 09:00:23.665  3201  3273 D ConnectivityService: Got NetworkFactory Messenger for WIFI
01-01 09:00:23.665  3201  3273 D ConnectivityService: Got NetworkFactory Messenger for WIFI_UT
01-01 09:00:23.679  3201  3271 D WIFI    : got request NetworkRequest [ id=1, legacyType=-1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ] with score 0
01-01 09:00:23.679  3201  3271 D WIFI_UT : got request NetworkRequest [ id=1, legacyType=-1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN] ] with score 0
01-01 09:00:24.909  3201  3272 D WifiService: New client listening to asynchronous messages
01-01 09:00:25.037  3201  3271 D WifiNative-HAL: Failing getSupportedFeatureset because HAL isn't started
01-01 09:00:25.832  3496  3981 I GCoreUlr: Registered for wifi state change updates
01-01 09:00:26.054  3496  3981 I GCoreUlr: GMS AR updates requested: UlrSampleSpec{mSamplePeriodMs=120000, mIncludeWifiScans=true, mSampleMinMillis=15000, mActivitySampleMillis=240000, mMaxWaitTimeMillis=600000, mSourcePackage=10051:com.google.android.gms}
01-01 09:00:26.143  3496  3981 I GCoreUlr: Starting service, intent=Intent { act=com.google.android.location.internal.GMS_NLP pkg=com.google.android.gms (has extras) }, extras=Bundle[{nlp.WIFI_SCAN_INTENT=PendingIntent{cbb99c9: android.os.BinderProxy@f31e8ce}}]
01-01 09:00:30.478  3201  3272 D WifiService: New client listening to asynchronous messages
01-01 09:00:35.460  3201  3272 D WifiService: New client listening to asynchronous messages
01-01 09:00:36.675  3201  3271 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 09:00:37.541  3201  3271 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 09:00:37.558  4117  4117 I DiscoveryManager: WifiGuestModeDeviceScanner enabled.
01-01 09:00:38.032  3201  3271 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 1000 -> 1000
01-01 09:00:38.033  3201  3271 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=1 roam=3
01-01 09:00:38.033  3201  3271 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "HK-N750-CHRIS-2.4G"WPA_PSK to any
01-01 09:00:38.041  3201  3271 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "HK-N750-CHRIS-2.4G" nid=1
01-01 09:00:38.103  3201  3271 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=1
01-01 09:00:38.211  3201  3271 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 09:00:38.227  3201  3271 D ConnectivityService: registerNetworkAgent NetworkAgentInfo{ ni{[type: WIFI[], state: CONNECTING/CONNECTING, reason: (unspecified), extra: "HK-N750-CHRIS-2.4G", roaming: false, failover: false, isAvailable: true]}  network{100}  lp{{LinkAddresses: []  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} lingering{false} explicitlySelected{false} acceptUnvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalDetected{false} }
01-01 09:00:38.227  3201  3271 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "HK-N750-CHRIS-2.4G"WPA_PSK to any
01-01 09:00:38.228  3201  3273 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, going from null to CONNECTING
01-01 09:00:38.251  3201  3271 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "HK-N750-CHRIS-2.4G"WPA_PSK to any
01-01 09:00:38.279  3201  3271 D WifiStateMachine: Start Dhcp Watchdog 1
01-01 09:00:38.292  3201  3273 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
01-01 09:00:38.293  3201  3273 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 60
01-01 09:00:38.388  3201  3271 D WifiConfigStore: No blacklist allowed without epno enabled
01-01 09:00:38.389  3201  3273 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, going from CONNECTING to CONNECTED
01-01 09:00:38.395  3201  3271 E WifiStateMachine: Did not find remoteAddress {192.168.1.1} in /proc/net/arp
01-01 09:00:38.431  3496  3496 I GCoreUlr: Starting service, intent=Intent { act=com.google.android.location.reporting.WIFI_TRIGGERED_FLUSH_AND_UPLOAD cmp=com.google.android.gms/com.google.android.location.reporting.service.DispatchingService }, extras=null
01-01 09:00:38.451  3201  3273 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
01-01 09:00:38.457  3201  3273 D ConnectivityService: updateSignalStrengthThresholds: CONNECT, sending [] to NetworkAgentInfo [WIFI () - 100]
01-01 09:00:38.457  3201  3273 D ConnectivityService: rematch for NetworkAgentInfo [WIFI () - 100]
01-01 09:00:38.457  3201  3271 D WifiNetworkAgent: NetworkAgent: Received signal strength thresholds: []
01-01 09:00:38.457  3201  3271 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
01-01 09:00:38.460  3201  3273 D ConnectivityService: Switching to new default network: NetworkAgentInfo{ ni{[type: WIFI[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: "HK-N750-CHRIS-2.4G", roaming: false, failover: false, isAvailable: true]}  network{100}  lp{{InterfaceName: wlan0 LinkAddresses: [fe80::7edd:90ff:fea9:ccf1/64,192.168.1.103/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: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}}  nc{[ Transports: WIFI Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=1048576Kbps LinkDnBandwidth>=1048576Kbps SignalStrength: -23]}  Score{20}  everValidated{false}  lastValidated{false}  created{true} lingering{false} explicitlySelected{false} acceptUnvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalDetected{false} }
01-01 09:00:38.517  3496  3496 I GeofencerStateMachine: sendWifiConnectivityChanged: connectivity=true
01-01 09:00:38.575  3201  3273 D CSLegacyTypeTracker: Sending CONNECTED broadcast for type 1 NetworkAgentInfo [WIFI () - 100] isDefaultNetwork=true
01-01 09:00:38.580  3201  3273 D ConnectivityService: notifyType PRECHECK for NetworkAgentInfo [WIFI () - 100]
01-01 09:00:38.584  3201  4784 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: Checking http://connectivitycheck.gstatic.com/generate_204 on "HK-N750-CHRIS-2.4G", connectivitycheck.gstatic.com=172.217.24.195,2404:6800:4005:808::2003
01-01 09:00:38.693  3201  4784 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: isCaptivePortal: ret=204 headers={null=[HTTP/1.1 204 No Content], Content-Length=[0], Date=[Tue, 06 Feb 2018 05:01:20 GMT], X-Android-Received-Millis=[38692], X-Android-Response-Source=[NETWORK 204], X-Android-Selected-Protocol=[http/1.1], X-Android-Sent-Millis=[38642]}
01-01 09:00:38.695  3201  3273 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] validation  passed
01-01 09:00:38.695  3201  3273 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
01-01 09:00:38.746  3496  3969 I GCoreUlr: Starting service, intent=Intent { act=com.google.android.location.reporting.WIFI_TRIGGERED_UPLOAD cmp=com.google.android.gms/com.google.android.location.reporting.service.DispatchingService }, extras=null
02-06 14:01:21.177  3201  3201 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-06 14:01:21.722  4117  4476 I ProcessCheckinRequest: d: "wifi"
02-06 14:01:21.722  4117  4476 I ProcessCheckinRequest:   m: "android.hardware.wifi"
02-06 14:01:22.281  3201  3272 D WifiService: New client listening to asynchronous messages
02-06 14:01:23.258  3201  3273 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-06 14:01:23.762  3201  4784 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: Forcing reevaluation for UID 10051
02-06 14:01:23.823  3201  4784 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: Checking http://connectivitycheck.gstatic.com/generate_204 on "HK-N750-CHRIS-2.4G", connectivitycheck.gstatic.com=172.217.24.195
02-06 14:01:23.873  3201  4784 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: isCaptivePortal: ret=204 headers={null=[HTTP/1.1 204 No Content], Content-Length=[0], Date=[Tue, 06 Feb 2018 05:01:23 GMT], X-Android-Received-Millis=[1517893283872], X-Android-Response-Source=[NETWORK 204], X-Android-Selected-Protocol=[http/1.1], X-Android-Sent-Millis=[1517893283825]}
02-06 14:01:23.874  3201  3273 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] validation  passed
02-06 14:01:33.640  3201  3271 D WifiStateMachine: L2Connected CMD_START_SCAN source -2 3, 4 -> obsolete
02-06 14:02:05.323  3201  3273 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 57
02-06 14:02:08.329  3201  3273 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
User avatar
codewalker
 
Posts: 480
Joined: Mon Feb 25, 2013 11:03 am
languages_spoken: english
ODROIDs: all

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby montaigne » Wed Feb 07, 2018 12:15 am

Code: Select all
root@odroidc2:/ # uname -a
Linux localhost 3.14.29 #1 SMP PREEMPT Mon Feb 5 11:43:27 KST 2018 armv8l
root@odroidc2:/ # getprop ro.bootimage.build.fingerprint
ODROID/odroidc2/odroidc2:6.0.1/MOI10E/odroidc2-eng-s905_6.0.1_master-88:eng/test-keys

root@odroidc2:/ # logcat | grep -i wifi
01-01 00:00:12.966  2867  2867 I AudioFlinger: loadHwModule() Loaded r_submix audio interface from Wifi Display audio HAL
(audio) handle 5
01-01 00:00:20.049  3212  3212 D lights.odroid: open_lights: wifi light isn't supported yet.
01-01 00:00:23.463  3212  3212 W PackageParser: Ignoring duplicate uses-permissions/uses-permissions-sdk-m: android.permis
sion.CONFIGURE_WIFI_DISPLAY in package: com.android.systemui at: Binary XML file line #120
01-01 00:00:30.028  3212  3212 I SystemServiceManager: Starting com.android.server.wifi.p2p.WifiP2pService
01-01 00:00:30.135  3212  3212 I WifiP2pService: Registering wifip2p
01-01 00:00:30.136  3212  3212 I SystemServiceManager: Starting com.android.server.wifi.WifiService
01-01 00:00:30.272  3212  3212 D WifiController: isAirplaneModeOn = false, isWifiEnabled = true, isScanningAvailable = fal
se
01-01 00:00:30.274  3212  3212 I WifiService: Registering wifi
01-01 00:00:30.275  3212  3212 I SystemServiceManager: Starting com.android.server.wifi.WifiScanningService
01-01 00:00:30.275  3212  3212 I WifiScanningService: Creating wifiscanner
01-01 00:00:30.276  3212  3212 I WifiScanningService: Starting wifiscanner
01-01 00:00:30.277  3212  3212 I SystemServiceManager: Starting com.android.server.wifi.RttService
01-01 00:00:30.290  3212  3212 D ConnectivityService: wifiOnly=false
01-01 00:00:30.468  3212  3259 D WifiApConfigStore: 2G band allowed channels are:1,6,11
01-01 00:00:30.497  3212  3212 I WifiService: WifiService starting up with Wi-Fi enabled
01-01 00:00:30.499  3212  3212 D WifiService: setWifiEnabled: true pid=3212, uid=1000
01-01 00:00:30.499  3212  3212 E WifiService: Invoking mWifiStateMachine.setWifiEnabled
01-01 00:00:30.500  3212  3261 D WifiController: WifiController msg { when=0 what=155656 target=com.android.internal.util.
StateMachine$SmHandler } deferred for 500ms
01-01 00:00:30.503  3212  3261 D WifiService: New client listening to asynchronous messages
01-01 00:00:30.504  3212  3212 I WifiScanningService: Registering wifiscanner
01-01 00:00:31.005  3212  3261 D WifiController: DEFERRED_TOGGLE handled
01-01 00:00:31.013  3212  3259 I WifiHW  : USB WiFi device is detected, [148f:5370]
01-01 00:00:31.017  3212  3259 I WifiHW  : Loading /system/lib/modules/backports/compat.ko
01-01 00:00:31.275  3212  3259 I WifiHW  : Loading /system/lib/modules/backports/cfg80211.ko
01-01 00:00:33.848  3212  3261 D WifiService: New client listening to asynchronous messages
01-01 00:00:33.912  3212  3259 I WifiHW  : Loading /system/lib/modules/backports/mac80211.ko
01-01 00:00:34.866  3212  3261 D WifiService: New client listening to asynchronous messages
01-01 00:00:35.859  3212  3259 I WifiHW  : Loading /system/lib/modules/backports/rt2x00lib.ko
01-01 00:00:36.213  3212  3259 I WifiHW  : Loading /system/lib/modules/backports/rt2x00usb.ko
01-01 00:00:36.852  3212  3259 I WifiHW  : Loading /system/lib/modules/backports/rt2800lib.ko
01-01 00:00:37.178  3212  3261 D WifiService: New client listening to asynchronous messages
01-01 00:00:37.182  3212  3259 I WifiHW  : Loading /system/lib/modules/backports/rt2800usb.ko
01-01 00:00:37.692  3212  3259 D WifiHW  : check loading wifi driver is ok...
01-01 00:00:37.692  3212  3259 E WifiHW  : driver loaded
01-01 00:00:37.698  3212  3259 E WifiMonitor: killSupplicant p2pfalse init.svc.wpa_supplicant=unknown init.svc.p2p_supplic
ant=unknown
01-01 00:00:37.799  3212  3259 D wifi    : Can not initialize the vendor function pointer table
01-01 00:00:37.799  3212  3259 E WifiNative-HAL: Could not start hal
01-01 00:00:37.799  3212  3259 E WifiStateMachine: Failed to start HAL
01-01 00:00:37.901  3212  3259 D WifiMonitor: startMonitoring(wlan0) with mConnected = false
01-01 00:00:38.373  3212  3259 D WIFI    : Registering NetworkFactory
01-01 00:00:38.373  3212  3259 D WIFI_UT : Registering NetworkFactory
01-01 00:00:38.374  3212  3262 D ConnectivityService: Got NetworkFactory Messenger for WIFI
01-01 00:00:38.374  3212  3262 D ConnectivityService: Got NetworkFactory Messenger for WIFI_UT
01-01 00:00:38.376  3212  3259 D WifiConfigStore: Loading config and enabling all networks
01-01 00:00:38.382  3212  3259 D WifiConfigStore: loaded 0 passpoint configs
01-01 00:00:38.387  3212  3259 W WifiConfigStore: Upgrading network 0 to android.uid.system:1000
01-01 00:00:38.388  3212  3259 E WifiConfigStore: readAutoJoinStatus: Error parsing configurationjava.io.FileNotFoundExcep
tion: /data/misc/wifi/autojoinconfig.txt: open failed: ENOENT (No such file or directory)
01-01 00:00:38.389  3212  3259 E WifiConfigStore: buildPnoList sortedWifiConfigurations size 1
01-01 00:00:38.389  3212  3259 E WifiConfigStore: found sortedWifiConfigurations : "Alcatel LINKZONE 5979"WPA_PSK
01-01 00:00:38.393  3212  3259 D WifiNative-HAL: Setting external_sim to 1
01-01 00:00:38.393  3212  3259 D WifiStateMachine: Setting OUI to DA-A1-19
01-01 00:00:38.397  3212  3259 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:38.398  3212  3259 E WifiStateMachine: setScanAlarm after 3 sec.
01-01 00:00:38.399  3212  3276 E WifiScanningService: could not start HAL
01-01 00:00:38.399  3212  3259 D WifiNative-HAL: Failing getSupportedFeatureset because HAL isn't started
01-01 00:00:38.402  3212  3259 D WIFI    : got request NetworkRequest [ id=1, legacyType=-1, [ Capabilities: INTERNET&NOT_
RESTRICTED&TRUSTED&NOT_VPN] ] with score 0
01-01 00:00:38.402  3212  3259 D WIFI_UT : got request NetworkRequest [ id=1, legacyType=-1, [ Capabilities: INTERNET&NOT_
RESTRICTED&TRUSTED&NOT_VPN] ] with score 0
01-01 00:00:38.405  3212  3259 D WifiConfigStore: No blacklist allowed without epno enabled
01-01 00:00:38.405  3212  3259 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:39.692  3212  3261 D WifiService: New client listening to asynchronous messages
01-01 00:00:39.693  3212  3259 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 1000 -
> 1000
01-01 00:00:39.694  3212  3259 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=0 r
oam=3
01-01 00:00:39.694  3212  3259 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
01-01 00:00:39.720  3212  3259 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "Alcatel LINKZONE 5979" nid=0
01-01 00:00:39.790  3212  3259 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
01-01 00:00:40.167  3212  3259 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:40.173  3212  3259 D ConnectivityService: registerNetworkAgent NetworkAgentInfo{ ni{[type: WIFI[], state: CONN
ECTING/CONNECTING, reason: (unspecified), extra: "Alcatel LINKZONE 5979", roaming: false, failover: false, isAvailable: tr
ue]}  network{100}  lp{{LinkAddresses: []  Routes: [] DnsAddresses: [] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576
,2097152,262144,524288,1048576}}  nc{[ Transports: WIFI Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwi
dth>=1048576Kbps LinkDnBandwidth>=1048576Kbps]}  Score{20}  everValidated{false}  lastValidated{false}  created{false} lin
gering{false} explicitlySelected{false} acceptUnvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalDetecte
d{false} }
01-01 00:00:40.173  3212  3259 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
01-01 00:00:40.173  3212  3262 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, going f
rom null to CONNECTING
01-01 00:00:40.190  3212  3259 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
01-01 00:00:40.213  3212  3259 D WifiStateMachine: Start Dhcp Watchdog 1
01-01 00:00:40.218  3212  3262 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:40.218  3212  3262 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 60
01-01 00:00:40.320  3212  3259 D WifiConfigStore: No blacklist allowed without epno enabled
01-01 00:00:40.320  3212  3262 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, going f
rom CONNECTING to CONNECTED
01-01 00:00:40.326  3212  3259 E WifiStateMachine: Did not find remoteAddress {192.168.43.1} in /proc/net/arp
01-01 00:00:40.799  3212  3262 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:40.804  3212  3262 D ConnectivityService: updateSignalStrengthThresholds: CONNECT, sending [] to NetworkAgentI
nfo [WIFI () - 100]
01-01 00:00:40.804  3212  3262 D ConnectivityService: rematch for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:40.805  3212  3259 D WifiNetworkAgent: NetworkAgent: Received signal strength thresholds: []
01-01 00:00:40.805  3212  3259 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
01-01 00:00:40.805  3212  3262 D ConnectivityService: Switching to new default network: NetworkAgentInfo{ ni{[type: WIFI[]
, state: CONNECTED/CONNECTED, reason: (unspecified), extra: "Alcatel LINKZONE 5979", roaming: false, failover: false, isAv
ailable: true]}  network{100}  lp{{InterfaceName: wlan0 LinkAddresses: [fe80::7edd:90ff:feeb:bfe9/64,192.168.43.106/24,]
Routes: [fe80::/64 -> :: wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.4
3.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}}  nc{[ Transports: WIFI Capabilit
ies: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=1048576Kbps LinkDnBandwidth>=1048576Kbps SignalStrength: -39
]}  Score{20}  everValidated{false}  lastValidated{false}  created{true} lingering{false} explicitlySelected{false} accept
Unvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalDetected{false} }
01-01 00:00:40.853  3212  3262 D CSLegacyTypeTracker: Sending CONNECTED broadcast for type 1 NetworkAgentInfo [WIFI () - 1
00] isDefaultNetwork=true
01-01 00:00:40.856  3212  3262 D ConnectivityService: notifyType PRECHECK for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:40.860  3212  3212 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
01-01 00:00:41.179  3212  4135 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: Checking http://connectivitycheck.gstati
c.com/generate_204 on "Alcatel LINKZONE 5979", connectivitycheck.gstatic.com=216.58.192.163,2607:f8b0:4009:80d::2003
02-06 15:30:15.496  3212  4135 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: isCaptivePortal: ret=204 headers={null=[
HTTP/1.1 204 No Content], Content-Length=[0], Date=[Tue, 06 Feb 2018 15:30:15 GMT], X-Android-Received-Millis=[15179310154
95], X-Android-Response-Source=[NETWORK 204], X-Android-Selected-Protocol=[http/1.1], X-Android-Sent-Millis=[41213]}
02-06 15:30:15.498  3212  3262 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] validation  passed
02-06 15:30:15.498  3212  3262 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-06 15:30:15.793  3212  3262 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-06 15:30:15.807  3212  3262 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-06 15:30:15.811  3212  3262 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-06 15:30:17.449  3212  3262 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-06 15:30:20.451  3212  3262 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 57
02-06 15:30:32.471  3212  3262 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 60
02-06 15:30:34.560  3212  3259 D WifiStateMachine: L2Connected CMD_START_SCAN source -2 2, 4 -> obsolete
02-06 15:30:35.607  3212  3259 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:31:02.505  3212  3262 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 57
------------------------------------------------------------------
02-06 15:31:15.609  3212  3259 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:31:16.485  3212  3259 D WifiStateMachine: Lost IP layer provisioning! was: {InterfaceName: wlan0 LinkAddresses: [
fe80::7edd:90ff:feeb:bfe9/64,192.168.43.106/24,2600:1008:b122:efd4:7edd:90ff:feeb:bfe9/64,2600:1008:b122:efd4:3901:fa08:78
ce:ff75/64,]  Routes: [fe80::/64 -> :: wlan0,::/0 -> fe80::92b6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.
0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.43.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,26
2144,524288,1048576} now: {InterfaceName: wlan0 LinkAddresses: [fe80::7edd:90ff:feeb:bfe9/64,192.168.43.106/24,2600:1008:b
122:efd4:7edd:90ff:feeb:bfe9/64,2600:1008:b122:efd4:3901:fa08:78ce:ff75/64,]  Routes: [fe80::/64 -> :: wlan0,::/0 -> fe80:
:92b6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.43.1,
] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}
02-06 15:31:16.487  3212  3259 E WifiConfigStore: SSID temp disabled for  "Alcatel LINKZONE 5979"WPA_PSK had autoJoinStatu
s=0 self added false ephemeral false
02-06 15:31:16.487  3212  3259 E WifiConfigStore:  message=DHCP FAILURE
02-06 15:31:16.488  3212  3259 E WifiConfigStore: blacklisted "Alcatel LINKZONE 5979"WPA_PSK to 0 due to IP config failure
s, count=1 disableReason=0 90:b6:86:83:5f:5d ipfail=1
02-06 15:31:16.488  3212  3262 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-06 15:31:16.551  3212  3259 D WifiStateMachine: WifiStateMachine: Leaving Connected state
02-06 15:31:16.551  3212  3259 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-06 15:31:16.551  3212  3259 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:31:16.573  3212  3262 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, going f
rom CONNECTED to DISCONNECTED
02-06 15:31:16.573  3212  3262 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] got DISCONNECTED, was satisfying 2
02-06 15:31:16.575  3212  3259 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:31:16.578  3212  3262 D ConnectivityService: notifyType LOST for NetworkAgentInfo [WIFI () - 100]
02-06 15:31:16.592  3212  3259 D WifiStateMachine: Start Disconnecting Watchdog 1
02-06 15:31:16.593  3212  3259 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-06 15:31:16.635  3212  3262 D CSLegacyTypeTracker: Sending DISCONNECTED broadcast for type 1 NetworkAgentInfo [WIFI ()
- 100] isDefaultNetwork=true
02-06 15:31:16.638  3212  3259 D WifiNetworkAgent: NetworkAgent: NetworkAgent channel lost
02-06 15:31:16.638  3212  3259 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:31:16.641  3212  3212 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-06 15:31:17.926  3212  3259 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=5.75 rxSuccessRate=5.75 delta 1000 -
> 994
02-06 15:31:17.927  3212  3259 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=0 r
oam=3
02-06 15:31:17.927  3212  3259 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "Alcatel LINKZONE 5979" nid=0
02-06 15:31:17.957  3212  3259 D WifiConfigStore: Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to any
02-06 15:31:18.003  3212  3259 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-06 15:31:35.622  3212  3259 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:31:35.875  3212  3259 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=1.44 rxSuccessRate=1.44 delta 1000 -
> 1000
02-06 15:31:35.875  3212  3259 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=0 r
oam=3
02-06 15:31:35.875  3212  3259 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:31:35.899  3212  3259 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "Alcatel LINKZONE 5979" nid=0
02-06 15:31:35.950  3212  3259 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-06 15:31:50.636  3212  3259 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:32:05.650  3212  3259 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:32:20.664  3212  3259 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:32:35.676  3212  3259 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:32:50.680  3212  3259 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:33:05.694  3212  3259 D WifiConfigStore: Retrieve network priorities after PNO.


It will stay at WifiConfigStore: Retrieve network priorities after PNO. indefinately.

To reiterate steps, as I know what I am actually seeing is indeed a bug. I have tried different routers, locations (home and work) and even mobile hotspots. I have tested this with 25 machines at the same time and all exhibit the same bug in previous versions. I have tried this with 3.7 this morning and it continues to exhibit this behavior.

The only interaction with ODROID is connecting to the WiFi once.

Prerequisites) Only have 1 network setup in WiFi settings, otherwise, it jumps to the next available network as expected and you cannot view this bug.

Step 1) Connect ODroid to a WiFi network. Leave ODroid on and viewable. Do not touch ODROID Again.
Step 2) Turn network off itself (disconnect from power/cycle).
Step 3) Wait until ODroid shows WiFi went offline.
Step 3) Turn network back on.
Step 4) ODroid never reconnects to WiFi.
Last edited by montaigne on Wed Feb 07, 2018 2:59 am, edited 2 times in total.
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby montaigne » Wed Feb 07, 2018 12:48 am

Module 3:
Code: Select all
(audio) handle 5
01-01 00:00:20.452  3218  3218 D lights.odroid: open_lights: wifi light isn't supported yet.
01-01 00:00:23.872  3218  3218 W PackageParser: Ignoring duplicate uses-permissions/uses-permissions-sdk-m: android.permis
sion.CONFIGURE_WIFI_DISPLAY in package: com.android.systemui at: Binary XML file line #120
01-01 00:00:30.544  3218  3218 I SystemServiceManager: Starting com.android.server.wifi.p2p.WifiP2pService
01-01 00:00:30.653  3218  3218 I WifiP2pService: Registering wifip2p
01-01 00:00:30.655  3218  3218 I SystemServiceManager: Starting com.android.server.wifi.WifiService
01-01 00:00:30.790  3218  3218 D WifiController: isAirplaneModeOn = false, isWifiEnabled = true, isScanningAvailable = fal
se
01-01 00:00:30.791  3218  3218 I WifiService: Registering wifi
01-01 00:00:30.792  3218  3218 I SystemServiceManager: Starting com.android.server.wifi.WifiScanningService
01-01 00:00:30.792  3218  3218 I WifiScanningService: Creating wifiscanner
01-01 00:00:30.793  3218  3218 I WifiScanningService: Starting wifiscanner
01-01 00:00:30.794  3218  3218 I SystemServiceManager: Starting com.android.server.wifi.RttService
01-01 00:00:30.806  3218  3218 D ConnectivityService: wifiOnly=false
01-01 00:00:30.988  3218  3267 D WifiApConfigStore: 2G band allowed channels are:1,6,11
01-01 00:00:31.037  3218  3218 I WifiService: WifiService starting up with Wi-Fi enabled
01-01 00:00:31.039  3218  3218 D WifiService: setWifiEnabled: true pid=3218, uid=1000
01-01 00:00:31.039  3218  3218 E WifiService: Invoking mWifiStateMachine.setWifiEnabled
01-01 00:00:31.040  3218  3268 D WifiController: WifiController msg { when=0 what=155656 target=com.android.internal.util.
StateMachine$SmHandler } deferred for 500ms
01-01 00:00:31.043  3218  3268 D WifiService: New client listening to asynchronous messages
01-01 00:00:31.045  3218  3218 I WifiScanningService: Registering wifiscanner
01-01 00:00:31.547  3218  3268 D WifiController: DEFERRED_TOGGLE handled
01-01 00:00:31.554  3218  3267 I WifiHW  : USB WiFi device is detected, [0bda:8176]
01-01 00:00:31.557  3218  3267 I WifiHW  : Loading /system/lib/modules/8192cu.ko
01-01 00:00:31.994  3218  3267 D WifiHW  : check loading wifi driver is ok...
01-01 00:00:31.994  3218  3267 E WifiHW  : driver loaded
01-01 00:00:32.733  3218  3267 E WifiMonitor: killSupplicant p2pfalse init.svc.wpa_supplicant=unknown init.svc.p2p_supplic
ant=unknown
01-01 00:00:32.735  3218  3267 D wifi    : Can not initialize the vendor function pointer table
01-01 00:00:32.735  3218  3267 E WifiNative-HAL: Could not start hal
01-01 00:00:32.735  3218  3267 E WifiStateMachine: Failed to start HAL
01-01 00:00:32.838  3218  3267 D WifiMonitor: startMonitoring(wlan0) with mConnected = false
01-01 00:00:33.769  3218  3267 D WifiConfigStore: Loading config and enabling all networks
01-01 00:00:33.779  3218  3267 D WifiConfigStore: loaded 0 passpoint configs
01-01 00:00:33.823  3218  3267 W WifiConfigStore: Upgrading network 0 to android.uid.system:1000
01-01 00:00:33.824  3218  3267 E WifiConfigStore: readAutoJoinStatus: Error parsing configurationjava.io.FileNotFoundExcep
tion: /data/misc/wifi/autojoinconfig.txt: open failed: ENOENT (No such file or directory)
01-01 00:00:33.824  3218  3267 E WifiConfigStore: buildPnoList sortedWifiConfigurations size 1
01-01 00:00:33.824  3218  3267 E WifiConfigStore: found sortedWifiConfigurations : "Alcatel LINKZONE 5979"WPA_PSK
01-01 00:00:33.840  3218  3267 D WifiNative-HAL: Setting external_sim to 1
01-01 00:00:33.848  3218  3267 D WifiStateMachine: Setting OUI to DA-A1-19
01-01 00:00:33.856  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:33.863  3218  3267 E WifiStateMachine: setScanAlarm after 3 sec.
01-01 00:00:33.866  3218  3267 D WifiNative-HAL: Failing getSupportedFeatureset because HAL isn't started
01-01 00:00:33.882  3218  3267 D WifiConfigStore: No blacklist allowed without epno enabled
01-01 00:00:33.882  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:34.093  3218  3268 D WifiService: New client listening to asynchronous messages
01-01 00:00:34.194  3218  3284 E WifiScanningService: could not start HAL
01-01 00:00:34.567  3218  3268 D WifiService: New client listening to asynchronous messages
01-01 00:00:34.879  3218  3267 D WIFI    : Registering NetworkFactory
01-01 00:00:34.879  3218  3267 D WIFI_UT : Registering NetworkFactory
01-01 00:00:34.879  3218  3269 D ConnectivityService: Got NetworkFactory Messenger for WIFI
01-01 00:00:34.879  3218  3269 D ConnectivityService: Got NetworkFactory Messenger for WIFI_UT
01-01 00:00:34.885  3218  3267 D WIFI    : got request NetworkRequest [ id=1, legacyType=-1, [ Capabilities: INTERNET&NOT_
RESTRICTED&TRUSTED&NOT_VPN] ] with score 0
01-01 00:00:34.885  3218  3267 D WIFI_UT : got request NetworkRequest [ id=1, legacyType=-1, [ Capabilities: INTERNET&NOT_
RESTRICTED&TRUSTED&NOT_VPN] ] with score 0
01-01 00:00:35.281  3218  3268 D WifiService: New client listening to asynchronous messages
01-01 00:00:38.012  3218  3268 D WifiService: New client listening to asynchronous messages
01-01 00:00:38.598  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:39.914  3218  3267 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 1000 -
> 1000
01-01 00:00:39.915  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=0 r
oam=3
01-01 00:00:39.915  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
01-01 00:00:39.930  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "Alcatel LINKZONE 5979" nid=0
01-01 00:00:39.981  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
01-01 00:00:40.382  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:40.388  3218  3267 D ConnectivityService: registerNetworkAgent NetworkAgentInfo{ ni{[type: WIFI[], state: CONN
ECTING/CONNECTING, reason: (unspecified), extra: "Alcatel LINKZONE 5979", roaming: false, failover: false, isAvailable: tr
ue]}  network{100}  lp{{LinkAddresses: []  Routes: [] DnsAddresses: [] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576
,2097152,262144,524288,1048576}}  nc{[ Transports: WIFI Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwi
dth>=1048576Kbps LinkDnBandwidth>=1048576Kbps]}  Score{20}  everValidated{false}  lastValidated{false}  created{false} lin
gering{false} explicitlySelected{false} acceptUnvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalDetecte
d{false} }
01-01 00:00:40.389  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, going f
rom null to CONNECTING
01-01 00:00:40.389  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
01-01 00:00:40.422  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
01-01 00:00:40.465  3218  3267 D WifiStateMachine: Start Dhcp Watchdog 1
01-01 00:00:40.470  3218  3269 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:40.471  3218  3269 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 60
01-01 00:00:41.532  3218  3267 D WifiConfigStore: No blacklist allowed without epno enabled
01-01 00:00:41.532  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, going f
rom CONNECTING to CONNECTED
01-01 00:00:41.576  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:41.580  3218  3269 D ConnectivityService: updateSignalStrengthThresholds: CONNECT, sending [] to NetworkAgentI
nfo [WIFI () - 100]
01-01 00:00:41.580  3218  3269 D ConnectivityService: rematch for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:41.580  3218  3267 D WifiNetworkAgent: NetworkAgent: Received signal strength thresholds: []
01-01 00:00:41.580  3218  3267 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
01-01 00:00:41.582  3218  3269 D ConnectivityService: Switching to new default network: NetworkAgentInfo{ ni{[type: WIFI[]
, state: CONNECTED/CONNECTED, reason: (unspecified), extra: "Alcatel LINKZONE 5979", roaming: false, failover: false, isAv
ailable: true]}  network{100}  lp{{InterfaceName: wlan0 LinkAddresses: [fe80::200:1dff:fe47:2530/64,2600:1008:b122:efd4:20
0:1dff:fe47:2530/64,]  Routes: [fe80::/64 -> :: wlan0,::/0 -> fe80::92b6:86ff:fe83:5f5d wlan0,] DnsAddresses: [2001:4888:3
1:ff00:343:d::,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}}  nc{[ Transports: WIF
I Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=1048576Kbps LinkDnBandwidth>=1048576Kbps SignalSt
rength: -48]}  Score{20}  everValidated{false}  lastValidated{false}  created{true} lingering{false} explicitlySelected{fa
lse} acceptUnvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalDetected{false} }
01-01 00:00:41.638  3218  3269 D CSLegacyTypeTracker: Sending CONNECTED broadcast for type 1 NetworkAgentInfo [WIFI () - 1
00] isDefaultNetwork=true
01-01 00:00:41.641  3218  3269 D ConnectivityService: notifyType PRECHECK for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:41.646  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:41.648  3218  3218 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
01-01 00:00:41.967  3218  4114 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: Checking http://connectivitycheck.gstati
c.com/generate_204 on "Alcatel LINKZONE 5979", connectivitycheck.gstatic.com=2607:f8b0:4009:80d::2003,216.58.192.163
02-06 15:36:22.880  3218  4114 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: isCaptivePortal: ret=204 headers={null=[
HTTP/1.1 204 No Content], Content-Length=[0], Date=[Tue, 06 Feb 2018 15:36:22 GMT], X-Android-Received-Millis=[15179313828
78], X-Android-Response-Source=[NETWORK 204], X-Android-Selected-Protocol=[http/1.1], X-Android-Sent-Millis=[1517931382831
]}
02-06 15:36:22.881  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] validation  passed
02-06 15:36:22.881  3218  3269 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-06 15:36:23.668  3218  3267 D WifiConfigStore: No blacklist allowed without epno enabled
02-06 15:36:23.668  3218  3267 D WifiStateMachine: WifiStateMachine: Leaving Connected state
02-06 15:36:23.669  3218  3267 E WifiStateMachine: Did not find remoteAddress {192.168.43.1} in /proc/net/arp
02-06 15:36:23.926  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-06 15:36:23.938  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-06 15:36:24.278  3218  3269 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-06 15:36:34.407  3218  3267 D WifiStateMachine: L2Connected CMD_START_SCAN source -2 3, 5 -> obsolete
02-06 15:36:42.341  3218  3267 D WifiStateMachine: L2Connected CMD_START_SCAN source -2 4, 5 -> obsolete
02-06 15:37:03.672  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:37:43.675  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:38:03.676  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:38:43.678  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:39:03.679  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:39:23.681  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:40:03.681  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:40:23.683  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:40:36.104  3218  3267 D WifiStateMachine: Lost IP layer provisioning! was: {InterfaceName: wlan0 LinkAddresses: [
fe80::200:1dff:fe47:2530/64,2600:1008:b122:efd4:200:1dff:fe47:2530/64,2600:1008:b122:efd4:f9e2:7d8c:2868:739d/64,192.168.4
3.180/24,]  Routes: [fe80::/64 -> :: wlan0,::/0 -> fe80::92b6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.
0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.43.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,2621
44,524288,1048576} now: {InterfaceName: wlan0 LinkAddresses: [fe80::200:1dff:fe47:2530/64,2600:1008:b122:efd4:200:1dff:fe4
7:2530/64,2600:1008:b122:efd4:f9e2:7d8c:2868:739d/64,192.168.43.180/24,]  Routes: [fe80::/64 -> :: wlan0,::/0 -> fe80::92b
6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.43.1,] Do
mains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}
02-06 15:40:36.105  3218  3267 E WifiConfigStore: SSID temp disabled for  "Alcatel LINKZONE 5979"WPA_PSK had autoJoinStatu
s=0 self added false ephemeral false
02-06 15:40:36.105  3218  3267 E WifiConfigStore:  message=DHCP FAILURE
02-06 15:40:36.105  3218  3267 E WifiConfigStore: blacklisted "Alcatel LINKZONE 5979"WPA_PSK to 0 due to IP config failure
s, count=1 disableReason=0 90:b6:86:83:5f:5d ipfail=1
02-06 15:40:36.111  3218  3267 D WifiStateMachine: WifiStateMachine: Leaving Connected state
02-06 15:40:36.111  3218  3267 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-06 15:40:36.111  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:40:36.114  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-06 15:40:36.137  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, going f
rom CONNECTED to DISCONNECTED
02-06 15:40:36.138  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] got DISCONNECTED, was satisfying 2
02-06 15:40:36.139  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:40:36.144  3218  3269 D ConnectivityService: notifyType LOST for NetworkAgentInfo [WIFI () - 100]
02-06 15:40:36.188  3218  3267 D WifiStateMachine: Start Disconnecting Watchdog 1
02-06 15:40:36.188  3218  3267 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-06 15:40:36.194  3218  3269 D CSLegacyTypeTracker: Sending DISCONNECTED broadcast for type 1 NetworkAgentInfo [WIFI ()
- 100] isDefaultNetwork=true
02-06 15:40:36.199  3218  3218 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-06 15:40:36.258  3218  3267 D WifiNetworkAgent: NetworkAgent: NetworkAgent channel lost
02-06 15:40:36.258  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:40:37.449  3218  3267 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=6.75 rxSuccessRate=6.25 delta 1000 -
> 994
02-06 15:40:37.450  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=0 r
oam=3
02-06 15:40:37.450  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "Alcatel LINKZONE 5979" nid=0
02-06 15:40:37.480  3218  3267 D WifiConfigStore: Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to any
02-06 15:40:37.498  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-06 15:40:48.505  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:41:03.510  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:41:04.829  3218  3267 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.21 rxSuccessRate=0.20 delta 1000 -
> 1000
02-06 15:41:04.830  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=0 r
oam=3
02-06 15:41:04.830  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:41:04.855  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "Alcatel LINKZONE 5979" nid=0
02-06 15:41:04.909  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-06 15:41:05.119  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:41:05.123  3218  3267 D ConnectivityService: registerNetworkAgent NetworkAgentInfo{ ni{[type: WIFI[], state: CONN
ECTING/CONNECTING, reason: (unspecified), extra: "Alcatel LINKZONE 5979", roaming: false, failover: false, isAvailable: tr
ue]}  network{101}  lp{{InterfaceName: wlan0 LinkAddresses: []  Routes: [] DnsAddresses: [] Domains: null MTU: 0 TcpBuffer
Sizes: 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} lingering{false} explicitlySelected{false} acceptUnvalidated{false} everCaptivePortalDetected{false} las
tCaptivePortalDetected{false} }
02-06 15:41:05.123  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:41:05.123  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] EVENT_NETWORK_INFO_CHANGED, going f
rom null to CONNECTING
02-06 15:41:05.156  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:41:05.175  3218  3267 D WifiStateMachine: Start Dhcp Watchdog 2
02-06 15:41:05.177  3218  3269 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 101] to 60
02-06 15:41:05.786  3218  3269 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-06 15:41:05.786  3218  3267 D WifiConfigStore: No blacklist allowed without epno enabled
02-06 15:41:05.786  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] EVENT_NETWORK_INFO_CHANGED, going f
rom CONNECTING to CONNECTED
02-06 15:41:05.790  3218  3267 E WifiStateMachine: Did not find remoteAddress {192.168.43.1} in /proc/net/arp
02-06 15:41:06.260  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-06 15:41:06.263  3218  3269 D ConnectivityService: updateSignalStrengthThresholds: CONNECT, sending [] to NetworkAgentI
nfo [WIFI () - 101]
02-06 15:41:06.263  3218  3269 D ConnectivityService: rematch for NetworkAgentInfo [WIFI () - 101]
02-06 15:41:06.263  3218  3267 D WifiNetworkAgent: NetworkAgent: Received signal strength thresholds: []
02-06 15:41:06.263  3218  3267 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-06 15:41:06.264  3218  3269 D ConnectivityService: Switching to new default network: NetworkAgentInfo{ ni{[type: WIFI[]
, state: CONNECTED/CONNECTED, reason: (unspecified), extra: "Alcatel LINKZONE 5979", roaming: false, failover: false, isAv
ailable: true]}  network{101}  lp{{InterfaceName: wlan0 LinkAddresses: [fe80::200:1dff:fe47:2530/64,192.168.43.180/24,]  R
outes: [fe80::/64 -> :: wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.43
.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}}  nc{[ Transports: WIFI Capabiliti
es: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=1048576Kbps LinkDnBandwidth>=1048576Kbps SignalStrength: -45]
}  Score{20}  everValidated{false}  lastValidated{false}  created{true} lingering{false} explicitlySelected{false} acceptU
nvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalDetected{false} }
02-06 15:41:06.310  3218  3269 D CSLegacyTypeTracker: Sending CONNECTED broadcast for type 1 NetworkAgentInfo [WIFI () - 1
01] isDefaultNetwork=true
02-06 15:41:06.311  3218  3269 D ConnectivityService: notifyType PRECHECK for NetworkAgentInfo [WIFI () - 101]
02-06 15:41:06.318  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-06 15:41:06.326  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-06 15:41:06.327  3218  3218 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-06 15:41:07.311  3218  3269 D ConnectivityService: NetTransition Wakelock (NetworkAgentInfo [WIFI () - 100] cleared bec
ause we found a replacement network
02-06 15:41:07.365  3218  4204 D NetworkMonitor/NetworkAgentInfo [WIFI () - 101]: Checking http://connectivitycheck.gstati
c.com/generate_204 on "Alcatel LINKZONE 5979", connectivitycheck.gstatic.com=2607:f8b0:4009:80d::2003,216.58.192.163
02-06 15:41:08.024  3218  4204 D NetworkMonitor/NetworkAgentInfo [WIFI () - 101]: isCaptivePortal: ret=204 headers={null=[
HTTP/1.1 204 No Content], Content-Length=[0], Date=[Tue, 06 Feb 2018 15:41:08 GMT], X-Android-Received-Millis=[15179316680
23], X-Android-Response-Source=[NETWORK 204], X-Android-Selected-Protocol=[http/1.1], X-Android-Sent-Millis=[1517931667608
]}
02-06 15:41:08.024  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] validation  passed
02-06 15:41:08.024  3218  3269 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-06 15:41:18.511  3218  3267 D WifiStateMachine: L2Connected CMD_START_SCAN source -2 19, 20 -> obsolete
02-06 15:41:45.816  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:42:25.820  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:42:45.822  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:43:25.823  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:43:45.823  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:44:05.824  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:44:45.829  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:45:05.829  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:45:25.832  3218  3267 D WifiStateMachine: starting scan for "Alcatel LINKZONE 5979"WPA_PSK with 2437
02-06 15:45:42.106  3218  3267 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-06 15:45:42.106  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:45:42.139  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] EVENT_NETWORK_INFO_CHANGED, going f
rom CONNECTED to DISCONNECTED
02-06 15:45:42.139  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] got DISCONNECTED, was satisfying 2
02-06 15:45:42.141  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:45:42.143  3218  3269 D ConnectivityService: notifyType LOST for NetworkAgentInfo [WIFI () - 101]
02-06 15:45:42.193  3218  3269 D CSLegacyTypeTracker: Sending DISCONNECTED broadcast for type 1 NetworkAgentInfo [WIFI ()
- 101] isDefaultNetwork=true
02-06 15:45:42.198  3218  3218 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-06 15:45:42.200  3218  3267 D WifiStateMachine: WifiStateMachine: Leaving Connected state
02-06 15:45:42.201  3218  3267 D WifiNetworkAgent: NetworkAgent: NetworkAgent channel lost
02-06 15:45:42.201  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:45:43.519  3218  3267 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=11.75 rxSuccessRate=11.75 delta 1000
 -> 994
02-06 15:45:43.520  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=0 r
oam=3
02-06 15:45:43.520  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "Alcatel LINKZONE 5979" nid=0
02-06 15:45:43.552  3218  3267 D WifiConfigStore: Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to any
02-06 15:45:43.570  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-06 15:45:54.578  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:46:09.591  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:46:24.605  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:46:39.618  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:46:54.628  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:47:09.641  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:47:24.654  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:47:26.550  3218  3267 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 1000 -
> 1000
02-06 15:47:26.551  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=0 r
oam=3
02-06 15:47:26.551  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:47:26.572  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "Alcatel LINKZONE 5979" nid=0
02-06 15:47:26.622  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-06 15:47:26.745  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:47:26.749  3218  3267 D ConnectivityService: registerNetworkAgent NetworkAgentInfo{ ni{[type: WIFI[], state: CONN
ECTING/CONNECTING, reason: (unspecified), extra: "Alcatel LINKZONE 5979", roaming: false, failover: false, isAvailable: tr
ue]}  network{102}  lp{{InterfaceName: wlan0 LinkAddresses: []  Routes: [] DnsAddresses: [] Domains: null MTU: 0 TcpBuffer
Sizes: 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} lingering{false} explicitlySelected{false} acceptUnvalidated{false} everCaptivePortalDetected{false} las
tCaptivePortalDetected{false} }
02-06 15:47:26.749  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 102] EVENT_NETWORK_INFO_CHANGED, going f
rom null to CONNECTING
02-06 15:47:26.749  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:47:26.765  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:47:26.784  3218  3267 D WifiStateMachine: Start Dhcp Watchdog 3
02-06 15:47:26.789  3218  3269 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 102] to 60
02-06 15:47:26.918  3218  3267 D WifiConfigStore: No blacklist allowed without epno enabled
02-06 15:47:26.918  3218  3269 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 102]
02-06 15:47:26.918  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 102] EVENT_NETWORK_INFO_CHANGED, going f
rom CONNECTING to CONNECTED
02-06 15:47:26.921  3218  3267 E WifiStateMachine: Did not find remoteAddress {192.168.43.1} in /proc/net/arp
02-06 15:47:27.392  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 102]
02-06 15:47:27.396  3218  3269 D ConnectivityService: updateSignalStrengthThresholds: CONNECT, sending [] to NetworkAgentI
nfo [WIFI () - 102]
02-06 15:47:27.396  3218  3269 D ConnectivityService: rematch for NetworkAgentInfo [WIFI () - 102]
02-06 15:47:27.396  3218  3267 D WifiNetworkAgent: NetworkAgent: Received signal strength thresholds: []
02-06 15:47:27.396  3218  3267 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-06 15:47:27.397  3218  3269 D ConnectivityService: Switching to new default network: NetworkAgentInfo{ ni{[type: WIFI[]
, state: CONNECTED/CONNECTED, reason: (unspecified), extra: "Alcatel LINKZONE 5979", roaming: false, failover: false, isAv
ailable: true]}  network{102}  lp{{InterfaceName: wlan0 LinkAddresses: [fe80::200:1dff:fe47:2530/64,192.168.43.180/24,]  R
outes: [fe80::/64 -> :: wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.43
.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}}  nc{[ Transports: WIFI Capabiliti
es: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=1048576Kbps LinkDnBandwidth>=1048576Kbps SignalStrength: -45]
}  Score{20}  everValidated{false}  lastValidated{false}  created{true} lingering{false} explicitlySelected{false} acceptU
nvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalDetected{false} }
02-06 15:47:27.445  3218  3269 D CSLegacyTypeTracker: Sending CONNECTED broadcast for type 1 NetworkAgentInfo [WIFI () - 1
02] isDefaultNetwork=true
02-06 15:47:27.447  3218  3269 D ConnectivityService: notifyType PRECHECK for NetworkAgentInfo [WIFI () - 102]
02-06 15:47:27.723  3218  3218 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-06 15:47:27.867  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 102]
02-06 15:47:27.874  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 102]
02-06 15:47:27.878  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 102]
02-06 15:47:27.920  3218  4317 D NetworkMonitor/NetworkAgentInfo [WIFI () - 102]: Checking http://connectivitycheck.gstati
c.com/generate_204 on "Alcatel LINKZONE 5979", connectivitycheck.gstatic.com=2607:f8b0:4009:80d::2003,216.58.192.163
02-06 15:47:32.030  3218  3267 D WifiStateMachine: Lost IP layer provisioning! was: {InterfaceName: wlan0 LinkAddresses: [
fe80::200:1dff:fe47:2530/64,192.168.43.180/24,2600:1008:b122:efd4:200:1dff:fe47:2530/64,2600:1008:b122:efd4:f9e2:7d8c:2868
:739d/64,]  Routes: [fe80::/64 -> :: wlan0,::/0 -> fe80::92b6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.
0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.43.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,2621
44,524288,1048576} now: {InterfaceName: wlan0 LinkAddresses: [fe80::200:1dff:fe47:2530/64,192.168.43.180/24,2600:1008:b122
:efd4:200:1dff:fe47:2530/64,2600:1008:b122:efd4:f9e2:7d8c:2868:739d/64,]  Routes: [fe80::/64 -> :: wlan0,::/0 -> fe80::92b
6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.43.1,] Do
mains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}
02-06 15:47:32.033  3218  3267 E WifiConfigStore: SSID temp disabled for  "Alcatel LINKZONE 5979"WPA_PSK had autoJoinStatu
s=0 self added false ephemeral false
02-06 15:47:32.033  3218  3267 E WifiConfigStore:  message=DHCP FAILURE
02-06 15:47:32.033  3218  3267 E WifiConfigStore: blacklisted "Alcatel LINKZONE 5979"WPA_PSK to 0 due to IP config failure
s, count=1 disableReason=0 90:b6:86:83:5f:5d ipfail=1
02-06 15:47:32.034  3218  3269 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 102]
02-06 15:47:32.039  3218  3267 D WifiStateMachine: WifiStateMachine: Leaving Connected state
02-06 15:47:32.039  3218  3267 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-06 15:47:32.040  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:47:32.084  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 102] EVENT_NETWORK_INFO_CHANGED, going f
rom CONNECTED to DISCONNECTED
02-06 15:47:32.084  3218  3269 D ConnectivityService: NetworkAgentInfo [WIFI () - 102] got DISCONNECTED, was satisfying 2
02-06 15:47:32.084  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:47:32.089  3218  3269 D ConnectivityService: notifyType LOST for NetworkAgentInfo [WIFI () - 102]
02-06 15:47:32.105  3218  3267 D WifiStateMachine: Start Disconnecting Watchdog 2
02-06 15:47:32.105  3218  3267 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-06 15:47:32.137  3218  3269 D CSLegacyTypeTracker: Sending DISCONNECTED broadcast for type 1 NetworkAgentInfo [WIFI ()
- 102] isDefaultNetwork=true
02-06 15:47:32.141  3218  3218 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-06 15:47:32.199  3218  3267 D WifiNetworkAgent: NetworkAgent: NetworkAgent channel lost
02-06 15:47:32.199  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:47:33.372  3218  3267 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=15.75 rxSuccessRate=14.25 delta 1000
 -> 994
02-06 15:47:33.372  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT sup state DisconnectedState my state DisconnectedState
 nid=0 roam=3
02-06 15:47:33.372  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "Alcatel LINKZONE 5979" nid=0
02-06 15:47:33.403  3218  3267 D WifiConfigStore: Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to any
02-06 15:47:33.423  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-06 15:47:37.929  3218  4317 D NetworkMonitor/NetworkAgentInfo [WIFI () - 102]: Probably not a portal: exception java.ne
t.SocketException: Binding socket to network 102 failed: ENONET (Machine is not on the network)
^A02-06 15:47:44.434  3218  3267 D WifiStateMachine: Disconnected CMD_START_SCAN source -2 40, 41 -> obsolete
02-06 15:47:46.949  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
02-06 15:47:48.840  3218  3267 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=3.94 rxSuccessRate=3.56 delta 1000 -
> 1000
02-06 15:47:48.841  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=0 r
oam=3
02-06 15:47:48.841  3218  3267 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "Alcatel LINKZONE 5979"WPA_PSK to
any
02-06 15:47:48.866  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "Alcatel LINKZONE 5979" nid=0
02-06 15:47:48.922  3218  3267 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-06 15:48:01.958  3218  3267 D WifiConfigStore: Retrieve network priorities after PNO.
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby odroid » Mon Feb 12, 2018 2:07 pm

We could reproduce the issue with WiFi module 0 and 4 while we turned off and on the AP power.
I think the Ralink kernel driver seems to have an issue. We will look into the issue but it might take a long time to find a root cause.
We will try to reproduce the issue with Ubuntu image to narrow down the root causes.
User avatar
odroid
Site Admin
 
Posts: 27746
Joined: Fri Feb 22, 2013 11:14 pm
languages_spoken: English
ODROIDs: ODROID

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby montaigne » Tue Feb 13, 2018 12:21 am

Thank you for looking at this issue.

I'll either downgrade to 5.1 or use a different module until a fix/root cause is found. If there is a way to patch it manually in the meantime let me know!
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby odroid » Tue Feb 13, 2018 9:26 am

Ubuntu has no such issue.
Unfortunately, Ubuntu uses a very different Ralink driver which can't be ported to Android Kernel/network framework easily.
We need much more time.
User avatar
odroid
Site Admin
 
Posts: 27746
Joined: Fri Feb 22, 2013 11:14 pm
languages_spoken: English
ODROIDs: ODROID

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby montaigne » Tue Feb 13, 2018 1:06 pm

Driver seems working in 5.1 just fine, but i am not sure how compatible they are.
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby codewalker » Mon Feb 26, 2018 5:36 pm

Try new version!

https://wiki.odroid.com/odroid-c2/os_im ... allow_v3.8

montaigne wrote:Driver seems working in 5.1 just fine, but i am not sure how compatible they are.
User avatar
codewalker
 
Posts: 480
Joined: Mon Feb 25, 2013 11:03 am
languages_spoken: english
ODROIDs: all

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby montaigne » Tue Feb 27, 2018 1:54 am

Just tested 10 machines with OTA update, the update does not appear to work. I am testing the self-install now and will report back.
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby montaigne » Tue Feb 27, 2018 2:22 am

I updated 12 machines via OTA, though I will continue to test a few more, none of the OTA updated work as expected.

I have done 5 self-install and the following occurs:

*** EDIT ***
While the self install appears to work properly right away (on reboot and quick restarts of the router), if I have the router off for a period of time (5 minutes or so), it no longer attempts to reconnect. I will logcat when shortly

Code: Select all
root@odroidc2:/ # uname -a
Linux localhost 3.14.29 #1 SMP PREEMPT Mon Feb 26 16:16:45 KST 2018 armv8l
root@odroidc2:/ # getprop ro.bootimage.build.fingerprint
ODROID/odroidc2/odroidc2:6.0.1/MOI10E/odroidc2-eng-s905_6.0.1_master-90:eng/test-keys
root@odroidc2:/ # logcat | grep -i wifi
01-01 00:00:11.876  2851  2851 I AudioFlinger: loadHwModule() Loaded r_submix audio interface from Wifi Display audio H
AL (audio) handle 5
01-01 00:00:13.094  3191  3191 D lights.odroid: open_lights: wifi light isn't supported yet.
01-01 00:00:14.899  3191  3191 W PackageParser: Ignoring duplicate uses-permissions/uses-permissions-sdk-m: android.per
mission.CONFIGURE_WIFI_DISPLAY in package: com.android.systemui at: Binary XML file line #120
01-01 00:00:18.580  3191  3191 I SystemServiceManager: Starting com.android.server.wifi.p2p.WifiP2pService
01-01 00:00:18.637  3191  3191 I WifiP2pService: Registering wifip2p
01-01 00:00:18.639  3191  3191 I SystemServiceManager: Starting com.android.server.wifi.WifiService
01-01 00:00:18.752  3191  3191 D WifiController: isAirplaneModeOn = false, isWifiEnabled = true, isScanningAvailable =
false
01-01 00:00:18.754  3191  3191 I WifiService: Registering wifi
01-01 00:00:18.755  3191  3191 I SystemServiceManager: Starting com.android.server.wifi.WifiScanningService
01-01 00:00:18.755  3191  3191 I WifiScanningService: Creating wifiscanner
01-01 00:00:18.755  3191  3191 I WifiScanningService: Starting wifiscanner
01-01 00:00:18.756  3191  3191 I SystemServiceManager: Starting com.android.server.wifi.RttService
01-01 00:00:18.767  3191  3191 D ConnectivityService: wifiOnly=false
01-01 00:00:18.946  3191  3191 I WifiService: WifiService starting up with Wi-Fi enabled
01-01 00:00:18.948  3191  3191 D WifiService: setWifiEnabled: true pid=3191, uid=1000
01-01 00:00:18.948  3191  3191 E WifiService: Invoking mWifiStateMachine.setWifiEnabled
01-01 00:00:18.949  3191  3239 D WifiController: WifiController msg { when=0 what=155656 target=com.android.internal.ut
il.StateMachine$SmHandler } deferred for 500ms
01-01 00:00:18.952  3191  3238 D WifiApConfigStore: 2G band allowed channels are:1,6,11
01-01 00:00:18.953  3191  3239 D WifiService: New client listening to asynchronous messages
01-01 00:00:18.955  3191  3191 I WifiScanningService: Registering wifiscanner
01-01 00:00:19.455  3191  3239 D WifiController: DEFERRED_TOGGLE handled
01-01 00:00:19.459  3191  3238 I WifiHW  : USB WiFi device is detected, [148f:5370]
01-01 00:00:19.460  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/compat.ko
01-01 00:00:19.699  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/cfg80211.ko
01-01 00:00:20.552  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/mac80211.ko
01-01 00:00:20.962  3191  3239 D WifiService: New client listening to asynchronous messages
01-01 00:00:21.495  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/rt2x00lib.ko
01-01 00:00:21.726  3191  3239 D WifiService: New client listening to asynchronous messages
01-01 00:00:21.777  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/rt2x00usb.ko
01-01 00:00:21.993  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/rt2800lib.ko
01-01 00:00:22.162  3191  3239 D WifiService: New client listening to asynchronous messages
01-01 00:00:22.220  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/rt2800usb.ko
01-01 00:00:22.684  3191  3238 D WifiHW  : check loading wifi driver is ok...
01-01 00:00:22.684  3191  3238 E WifiHW  : driver loaded
01-01 00:00:22.691  3191  3238 E WifiMonitor: killSupplicant p2pfalse init.svc.wpa_supplicant=unknown init.svc.p2p_supp
licant=unknown
01-01 00:00:22.791  3191  3238 D wifi    : Can not initialize the vendor function pointer table
01-01 00:00:22.791  3191  3238 E WifiNative-HAL: Could not start hal
01-01 00:00:22.792  3191  3238 E WifiStateMachine: Failed to start HAL
01-01 00:00:22.894  3191  3238 D WifiMonitor: startMonitoring(wlan0) with mConnected = false
01-01 00:00:23.572  3191  3238 D WIFI    : Registering NetworkFactory
01-01 00:00:23.572  3191  3238 D WIFI_UT : Registering NetworkFactory
01-01 00:00:23.572  3191  3240 D ConnectivityService: Got NetworkFactory Messenger for WIFI
01-01 00:00:23.572  3191  3240 D ConnectivityService: Got NetworkFactory Messenger for WIFI_UT
01-01 00:00:23.576  3191  3238 D WifiConfigStore: Loading config and enabling all networks
01-01 00:00:23.586  3191  3238 D WifiConfigStore: loaded 0 passpoint configs
01-01 00:00:23.586  3191  3238 E WifiConfigStore: Error parsing configuration: java.io.FileNotFoundException: /data/mis
c/wifi/ipconfig.txt: open failed: ENOENT (No such file or directory)
01-01 00:00:23.589  3191  3238 W WifiConfigStore: Upgrading network 0 to android.uid.system:1000
01-01 00:00:23.597  3191  3238 E WifiConfigStore: readAutoJoinStatus: Error parsing configurationjava.io.FileNotFoundEx
ception: /data/misc/wifi/autojoinconfig.txt: open failed: ENOENT (No such file or directory)
01-01 00:00:23.598  3191  3238 E WifiConfigStore: buildPnoList sortedWifiConfigurations size 1
01-01 00:00:23.598  3191  3238 E WifiConfigStore: found sortedWifiConfigurations : "bag-wifi1"WPA_PSK
01-01 00:00:23.623  3191  3238 D WifiNative-HAL: Setting external_sim to 1
01-01 00:00:23.624  3191  3238 D WifiStateMachine: Setting OUI to DA-A1-19
01-01 00:00:23.629  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:23.631  3191  3238 E WifiStateMachine: setScanAlarm after 3 sec.
01-01 00:00:23.632  3191  3238 D WifiNative-HAL: Failing getSupportedFeatureset because HAL isn't started
01-01 00:00:23.632  3191  3254 E WifiScanningService: could not start HAL
01-01 00:00:23.635  3191  3238 D WIFI    : got request NetworkRequest [ id=1, legacyType=-1, [ Capabilities: INTERNET&N
OT_RESTRICTED&TRUSTED&NOT_VPN] ] with score 0
01-01 00:00:23.635  3191  3238 D WIFI_UT : got request NetworkRequest [ id=1, legacyType=-1, [ Capabilities: INTERNET&N
OT_RESTRICTED&TRUSTED&NOT_VPN] ] with score 0
01-01 00:00:23.637  3191  3238 D WifiConfigStore: No blacklist allowed without epno enabled
01-01 00:00:23.638  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:24.758  3191  3239 D WifiService: New client listening to asynchronous messages
01-01 00:00:24.949  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 100
0 -> 1000
01-01 00:00:24.950  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
01-01 00:00:24.950  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
01-01 00:00:24.956  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
01-01 00:00:25.007  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
01-01 00:00:25.009  3742  3742 I wpa_supplicant: wlan0: SME: Trying to authenticate with 90:b6:86:83:5f:5d (SSID='bag-w
ifi1' freq=2462 MHz)
01-01 00:00:25.049  3742  3742 I wpa_supplicant: wlan0: Trying to associate with 90:b6:86:83:5f:5d (SSID='bag-wifi1' fr
eq=2462 MHz)
01-01 00:00:25.399  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:25.405  3191  3238 D ConnectivityService: registerNetworkAgent NetworkAgentInfo{ ni{[type: WIFI[], state: C
ONNECTING/CONNECTING, reason: (unspecified), extra: "bag-wifi1", roaming: false, failover: false, isAvailable: true]}
network{100}  lp{{LinkAddresses: []  Routes: [] DnsAddresses: [] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,20
97152,262144,524288,1048576}}  nc{[ Transports: WIFI Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwi
dth>=1048576Kbps LinkDnBandwidth>=1048576Kbps]}  Score{20}  everValidated{false}  lastValidated{false}  created{false}
lingering{false} explicitlySelected{false} acceptUnvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalD
etected{false} }
01-01 00:00:25.405  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
01-01 00:00:25.405  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, goin
g from null to CONNECTING
01-01 00:00:25.415  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
01-01 00:00:25.426  3191  3238 D WifiStateMachine: Start Dhcp Watchdog 1
01-01 00:00:25.431  3191  3240 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:25.431  3191  3240 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 60
01-01 00:00:25.514  3191  3238 D WifiConfigStore: No blacklist allowed without epno enabled
01-01 00:00:25.514  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, goin
g from CONNECTING to CONNECTED
01-01 00:00:25.517  3191  3238 E WifiStateMachine: Did not find remoteAddress {192.168.43.1} in /proc/net/arp
01-01 00:00:25.992  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:25.995  3191  3240 D ConnectivityService: updateSignalStrengthThresholds: CONNECT, sending [] to NetworkAge
ntInfo [WIFI () - 100]
01-01 00:00:25.995  3191  3240 D ConnectivityService: rematch for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:25.996  3191  3238 D WifiNetworkAgent: NetworkAgent: Received signal strength thresholds: []
01-01 00:00:25.996  3191  3238 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
01-01 00:00:25.996  3191  3240 D ConnectivityService: Switching to new default network: NetworkAgentInfo{ ni{[type: WIF
I[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: "bag-wifi1", roaming: false, failover: false, isAvailabl
e: true]}  network{100}  lp{{InterfaceName: wlan0 LinkAddresses: [fe80::7edd:90ff:fee1:50a9/64,192.168.43.179/24,]  Rou
tes: [fe80::/64 -> :: wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.4
3.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}}  nc{[ Transports: WIFI Capabi
lities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=1048576Kbps LinkDnBandwidth>=1048576Kbps SignalStrengt
h: -33]}  Score{20}  everValidated{false}  lastValidated{false}  created{true} lingering{false} explicitlySelected{fals
e} acceptUnvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalDetected{false} }
01-01 00:00:26.046  3191  3240 D CSLegacyTypeTracker: Sending CONNECTED broadcast for type 1 NetworkAgentInfo [WIFI ()
- 100] isDefaultNetwork=true
01-01 00:00:26.049  3191  3240 D ConnectivityService: notifyType PRECHECK for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:26.052  3191  3191 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
01-01 00:00:26.391  3191  4097 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: Checking http://connectivitycheck.gst
atic.com/generate_204 on "bag-wifi1", connectivitycheck.gstatic.com=216.58.192.227,2607:f8b0:4009:80f::2003
02-26 20:27:20.970  3191  4097 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: isCaptivePortal: ret=204 headers={nul
l=[HTTP/1.1 204 No Content], Content-Length=[0], Date=[Mon, 26 Feb 2018 20:27:20 GMT], X-Android-Received-Millis=[15196
76840969], X-Android-Response-Source=[NETWORK 204], X-Android-Selected-Protocol=[http/1.1], X-Android-Sent-Millis=[2643
0]}
02-26 20:27:20.975  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] validation  passed
02-26 20:27:20.975  3191  3240 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:21.243  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:21.249  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:22.917  3191  3240 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:25.918  3191  3240 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 57
02-26 20:27:31.927  3191  3240 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 60
02-26 20:27:40.005  3191  3238 D WifiStateMachine: L2Connected CMD_START_SCAN source -2 2, 4 -> obsolete
02-26 20:27:41.073  3191  3238 D WifiStateMachine: starting scan for "bag-wifi1"WPA_PSK with 2462
02-26 20:27:41.374  3191  3238 D WifiStateMachine: Lost IP layer provisioning! was: {InterfaceName: wlan0 LinkAddresses
: [fe80::7edd:90ff:fee1:50a9/64,192.168.43.179/24,2600:1008:b111:dc88:7edd:90ff:fee1:50a9/64,2600:1008:b111:dc88:74af:3
337:9c23:460d/64,]  Routes: [fe80::/64 -> :: wlan0,::/0 -> fe80::92b6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 w
lan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.43.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,104857
6,2097152,262144,524288,1048576} now: {InterfaceName: wlan0 LinkAddresses: [fe80::7edd:90ff:fee1:50a9/64,192.168.43.179
/24,2600:1008:b111:dc88:7edd:90ff:fee1:50a9/64,2600:1008:b111:dc88:74af:3337:9c23:460d/64,]  Routes: [fe80::/64 -> :: w
lan0,::/0 -> fe80::92b6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddr
esses: [192.168.43.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}
02-26 20:27:41.377  3191  3238 E WifiConfigStore: SSID temp disabled for  "bag-wifi1"WPA_PSK had autoJoinStatus=0 self
added false ephemeral false
02-26 20:27:41.377  3191  3238 E WifiConfigStore:  message=DHCP FAILURE
02-26 20:27:41.377  3191  3238 E WifiConfigStore: blacklisted "bag-wifi1"WPA_PSK to 0 due to IP config failures, count=
1 disableReason=0 90:b6:86:83:5f:5d ipfail=1
02-26 20:27:41.380  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:41.387  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:41.436  3191  3238 D WifiStateMachine: WifiStateMachine: Leaving Connected state
02-26 20:27:41.436  3191  3238 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-26 20:27:41.436  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:27:41.457  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, goin
g from CONNECTED to DISCONNECTED
02-26 20:27:41.457  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] got DISCONNECTED, was satisfying
 2
02-26 20:27:41.459  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:27:41.464  3191  3240 D ConnectivityService: notifyType LOST for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:41.464  3191  3238 D WifiStateMachine: Start Disconnecting Watchdog 1
02-26 20:27:41.465  3191  3238 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-26 20:27:41.510  3191  3240 D CSLegacyTypeTracker: Sending DISCONNECTED broadcast for type 1 NetworkAgentInfo [WIFI
() - 100] isDefaultNetwork=true
02-26 20:27:41.514  3191  3191 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-26 20:27:41.566  3191  3238 D WifiNetworkAgent: NetworkAgent: NetworkAgent channel lost
02-26 20:27:41.566  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:27:42.862  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=5.50 rxSuccessRate=4.50 delta 100
0 -> 994
02-26 20:27:42.862  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:27:42.862  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:27:42.896  3191  3238 D WifiConfigStore: Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:27:42.905  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:27:42.906  3742  3742 I wpa_supplicant: wlan0: SME: Trying to authenticate with 90:b6:86:83:5f:5d (SSID='bag-w
ifi1' freq=2462 MHz)
02-26 20:28:00.796  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=1.38 rxSuccessRate=1.12 delta 100
0 -> 1000
02-26 20:28:00.796  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:28:00.796  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:28:00.801  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:28:00.840  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:28:11.855  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:28:13.144  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.17 rxSuccessRate=0.14 delta 100
0 -> 1000
02-26 20:28:13.145  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:28:13.145  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:28:13.151  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:28:13.190  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:28:26.865  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:28:28.157  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.02 rxSuccessRate=0.02 delta 100
0 -> 1000
02-26 20:28:28.158  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:28:28.158  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:28:28.163  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:28:28.205  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:28:41.875  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:28:43.167  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 100
0 -> 1000
02-26 20:28:43.168  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:28:43.168  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:28:43.175  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:28:43.218  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:28:56.888  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:28:58.174  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 100
0 -> 1000
02-26 20:28:58.175  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:28:58.175  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:28:58.181  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:28:58.220  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:29:11.895  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:29:13.187  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 100
0 -> 1000
02-26 20:29:13.188  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:29:13.188  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:29:13.193  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:29:13.232  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:29:26.905  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:29:28.198  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 100
0 -> 1000
02-26 20:29:28.199  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:29:28.199  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:29:28.204  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:29:28.246  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:29:28.247  3742  3742 I wpa_supplicant: wlan0: SME: Trying to authenticate with 90:b6:86:83:5f:5d (SSID='bag-w
ifi1' freq=2462 MHz)
02-26 20:29:28.285  3742  3742 I wpa_supplicant: wlan0: Trying to associate with 90:b6:86:83:5f:5d (SSID='bag-wifi1' fr
eq=2462 MHz)
02-26 20:29:28.336  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:29:28.339  3191  3238 D ConnectivityService: registerNetworkAgent NetworkAgentInfo{ ni{[type: WIFI[], state: C
ONNECTING/CONNECTING, reason: (unspecified), extra: "bag-wifi1", roaming: false, failover: false, isAvailable: true]}
network{101}  lp{{InterfaceName: wlan0 LinkAddresses: []  Routes: [] DnsAddresses: [] Domains: null MTU: 0 TcpBufferSiz
es: 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{fa
lse}  created{false} lingering{false} explicitlySelected{false} acceptUnvalidated{false} everCaptivePortalDetected{fals
e} lastCaptivePortalDetected{false} }
02-26 20:29:28.339  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:29:28.339  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] EVENT_NETWORK_INFO_CHANGED, goin
g from null to CONNECTING
02-26 20:29:28.347  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:29:28.355  3191  3238 D WifiStateMachine: Start Dhcp Watchdog 2
02-26 20:29:28.358  3191  3240 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 101] to 60
02-26 20:29:28.425  3191  3238 D WifiConfigStore: No blacklist allowed without epno enabled
02-26 20:29:28.425  3191  3240 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:28.426  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] EVENT_NETWORK_INFO_CHANGED, goin
g from CONNECTING to CONNECTED
02-26 20:29:28.430  3191  3238 E WifiStateMachine: Did not find remoteAddress {192.168.43.1} in /proc/net/arp
02-26 20:29:28.914  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:28.917  3191  3240 D ConnectivityService: updateSignalStrengthThresholds: CONNECT, sending [] to NetworkAge
ntInfo [WIFI () - 101]
02-26 20:29:28.917  3191  3240 D ConnectivityService: rematch for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:28.918  3191  3238 D WifiNetworkAgent: NetworkAgent: Received signal strength thresholds: []
02-26 20:29:28.918  3191  3238 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-26 20:29:28.918  3191  3240 D ConnectivityService: Switching to new default network: NetworkAgentInfo{ ni{[type: WIF
I[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: "bag-wifi1", roaming: false, failover: false, isAvailabl
e: true]}  network{101}  lp{{InterfaceName: wlan0 LinkAddresses: [fe80::7edd:90ff:fee1:50a9/64,192.168.43.179/24,]  Rou
tes: [fe80::/64 -> :: wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.4
3.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}}  nc{[ Transports: WIFI Capabi
lities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=1048576Kbps LinkDnBandwidth>=1048576Kbps SignalStrengt
h: -27]}  Score{20}  everValidated{false}  lastValidated{false}  created{true} lingering{false} explicitlySelected{fals
e} acceptUnvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalDetected{false} }
02-26 20:29:28.964  3191  3240 D CSLegacyTypeTracker: Sending CONNECTED broadcast for type 1 NetworkAgentInfo [WIFI ()
- 101] isDefaultNetwork=true
02-26 20:29:28.966  3191  3240 D ConnectivityService: notifyType PRECHECK for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:29.244  3191  3191 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-26 20:29:29.298  3191  4249 D NetworkMonitor/NetworkAgentInfo [WIFI () - 101]: Checking http://connectivitycheck.gst
atic.com/generate_204 on "bag-wifi1", connectivitycheck.gstatic.com=216.58.192.227,2607:f8b0:4009:80f::2003
02-26 20:29:29.388  3191  4249 D NetworkMonitor/NetworkAgentInfo [WIFI () - 101]: isCaptivePortal: ret=204 headers={nul
l=[HTTP/1.1 204 No Content], Content-Length=[0], Date=[Mon, 26 Feb 2018 20:29:29 GMT], X-Android-Received-Millis=[15196
76969387], X-Android-Response-Source=[NETWORK 204], X-Android-Selected-Protocol=[http/1.1], X-Android-Sent-Millis=[1519
676969339]}
02-26 20:29:29.388  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] validation  passed
02-26 20:29:29.388  3191  3240 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:29.422  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:29.430  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:29.436  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:31.360  3191  3240 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:41.908  3191  3238 D WifiStateMachine: L2Connected CMD_START_SCAN source -2 11, 12 -> obsolete
02-26 20:29:55.387  3191  3240 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 101] to 57
02-26 20:30:08.436  3191  3238 D WifiStateMachine: starting scan for "bag-wifi1"WPA_PSK with 2462
02-26 20:30:25.310  3191  3238 D WifiStateMachine: Lost IP layer provisioning! was: {InterfaceName: wlan0 LinkAddresses
: [fe80::7edd:90ff:fee1:50a9/64,192.168.43.179/24,2600:1008:b111:dc88:7edd:90ff:fee1:50a9/64,2600:1008:b111:dc88:74af:3
337:9c23:460d/64,]  Routes: [fe80::/64 -> :: wlan0,::/0 -> fe80::92b6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 w
lan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.43.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,104857
6,2097152,262144,524288,1048576} now: {InterfaceName: wlan0 LinkAddresses: [fe80::7edd:90ff:fee1:50a9/64,192.168.43.179
/24,2600:1008:b111:dc88:7edd:90ff:fee1:50a9/64,2600:1008:b111:dc88:74af:3337:9c23:460d/64,]  Routes: [fe80::/64 -> :: w
lan0,::/0 -> fe80::92b6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddr
esses: [192.168.43.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}
02-26 20:30:25.311  3191  3238 E WifiConfigStore: SSID temp disabled for  "bag-wifi1"WPA_PSK had autoJoinStatus=0 self
added false ephemeral false
02-26 20:30:25.311  3191  3238 E WifiConfigStore:  message=DHCP FAILURE
02-26 20:30:25.311  3191  3238 E WifiConfigStore: blacklisted "bag-wifi1"WPA_PSK to 0 due to IP config failures, count=
1 disableReason=0 90:b6:86:83:5f:5d ipfail=1
02-26 20:30:25.318  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:30:25.326  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:30:25.376  3191  3238 D WifiStateMachine: WifiStateMachine: Leaving Connected state
02-26 20:30:25.376  3191  3238 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-26 20:30:25.376  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:30:25.391  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] EVENT_NETWORK_INFO_CHANGED, goin
g from CONNECTED to DISCONNECTED
02-26 20:30:25.392  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] got DISCONNECTED, was satisfying
 2
02-26 20:30:25.397  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:30:25.400  3191  3240 D ConnectivityService: notifyType LOST for NetworkAgentInfo [WIFI () - 101]
02-26 20:30:25.403  3191  3238 D WifiStateMachine: Start Disconnecting Watchdog 2
02-26 20:30:25.404  3191  3238 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-26 20:30:25.449  3191  3240 D CSLegacyTypeTracker: Sending DISCONNECTED broadcast for type 1 NetworkAgentInfo [WIFI
() - 101] isDefaultNetwork=true
02-26 20:30:25.452  3191  3191 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-26 20:30:25.504  3191  3238 D WifiNetworkAgent: NetworkAgent: NetworkAgent channel lost
02-26 20:30:25.504  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:30:26.794  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=10.00 rxSuccessRate=8.00 delta 10
00 -> 994
02-26 20:30:26.794  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:30:26.794  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:30:26.825  3191  3238 D WifiConfigStore: Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:30:26.834  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:30:26.836  3742  3742 I wpa_supplicant: wlan0: SME: Trying to authenticate with 90:b6:86:83:5f:5d (SSID='bag-w
ifi1' freq=2462 MHz)
02-26 20:30:37.875  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:30:39.166  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=1.25 rxSuccessRate=1.00 delta 100
0 -> 1000
02-26 20:30:39.167  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:30:39.167  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:30:39.173  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:30:39.212  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:30:52.885  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:30:54.174  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.16 rxSuccessRate=0.12 delta 100
0 -> 1000
02-26 20:30:54.175  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:30:54.175  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:30:54.181  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:30:54.224  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:31:07.895  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:31:09.185  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.02 rxSuccessRate=0.02 delta 100
0 -> 1000
02-26 20:31:09.185  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:31:09.185  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:31:09.192  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:31:09.233  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:31:22.905  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:31:37.920  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:31:52.934  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:32:07.945  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:32:22.960  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:32:37.973  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:32:52.988  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:33:07.995  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:33:23.005  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:33:38.020  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:33:53.033  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:34:08.047  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:34:23.061  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:34:38.065  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:34:53.075  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:35:08.090  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:35:23.103  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:35:38.118  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:35:53.132  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:36:08.146  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:36:23.160  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:36:38.174  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:36:53.187  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:37:08.195  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:37:23.205  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:37:38.215  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:37:53.225  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:38:08.239  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.


02-26 20:31:22.905 3191 3238 D WifiConfigStore: Retrieve network priorities after PNO. <-- Turned WiFi Off for 5 minute waiting period
02-26 20:36:38.174 3191 3238 D WifiConfigStore: Retrieve network priorities after PNO. <-- Turned WiFi On, waited 2-3 minutes with no reconnect.

Test 1:
Turn on ODROID with wifi already on
Restart wifi 1 minute after startup
ODROID WiFi reconnects as expected
Turn off wifi, wait 5 minutes
ODROID WiFi no longer reconnects as expected.

Test 2:
Wifi is off
Turn on Odroid
Wait 5 minutes
Turn on Wifi
Odroid Wifi does not attempt to reconnect.

As a note, i waited over 2 hours and it never reconnects.

With the same exact scenarios 5.1 works expected, v3.8 seems to stop working after a few minutes.
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby codewalker » Tue Feb 27, 2018 12:25 pm

Sorry, One more try this.

https://dn.odroid.com/S905/Android/test/marshmallow/

Download updatepackage-odroidc2-eng-s905_6.0.1_master-91.zip and run ODROID utility
select "Package install from storage" menu.

montaigne wrote:I updated 12 machines via OTA, though I will continue to test a few more, none of the OTA updated work as expected.

I have done 5 self-install and the following occurs:

*** EDIT ***
While the self install appears to work properly right away (on reboot and quick restarts of the router), if I have the router off for a period of time (5 minutes or so), it no longer attempts to reconnect. I will logcat when shortly

Code: Select all
root@odroidc2:/ # uname -a
Linux localhost 3.14.29 #1 SMP PREEMPT Mon Feb 26 16:16:45 KST 2018 armv8l
root@odroidc2:/ # getprop ro.bootimage.build.fingerprint
ODROID/odroidc2/odroidc2:6.0.1/MOI10E/odroidc2-eng-s905_6.0.1_master-90:eng/test-keys
root@odroidc2:/ # logcat | grep -i wifi
01-01 00:00:11.876  2851  2851 I AudioFlinger: loadHwModule() Loaded r_submix audio interface from Wifi Display audio H
AL (audio) handle 5
01-01 00:00:13.094  3191  3191 D lights.odroid: open_lights: wifi light isn't supported yet.
01-01 00:00:14.899  3191  3191 W PackageParser: Ignoring duplicate uses-permissions/uses-permissions-sdk-m: android.per
mission.CONFIGURE_WIFI_DISPLAY in package: com.android.systemui at: Binary XML file line #120
01-01 00:00:18.580  3191  3191 I SystemServiceManager: Starting com.android.server.wifi.p2p.WifiP2pService
01-01 00:00:18.637  3191  3191 I WifiP2pService: Registering wifip2p
01-01 00:00:18.639  3191  3191 I SystemServiceManager: Starting com.android.server.wifi.WifiService
01-01 00:00:18.752  3191  3191 D WifiController: isAirplaneModeOn = false, isWifiEnabled = true, isScanningAvailable =
false
01-01 00:00:18.754  3191  3191 I WifiService: Registering wifi
01-01 00:00:18.755  3191  3191 I SystemServiceManager: Starting com.android.server.wifi.WifiScanningService
01-01 00:00:18.755  3191  3191 I WifiScanningService: Creating wifiscanner
01-01 00:00:18.755  3191  3191 I WifiScanningService: Starting wifiscanner
01-01 00:00:18.756  3191  3191 I SystemServiceManager: Starting com.android.server.wifi.RttService
01-01 00:00:18.767  3191  3191 D ConnectivityService: wifiOnly=false
01-01 00:00:18.946  3191  3191 I WifiService: WifiService starting up with Wi-Fi enabled
01-01 00:00:18.948  3191  3191 D WifiService: setWifiEnabled: true pid=3191, uid=1000
01-01 00:00:18.948  3191  3191 E WifiService: Invoking mWifiStateMachine.setWifiEnabled
01-01 00:00:18.949  3191  3239 D WifiController: WifiController msg { when=0 what=155656 target=com.android.internal.ut
il.StateMachine$SmHandler } deferred for 500ms
01-01 00:00:18.952  3191  3238 D WifiApConfigStore: 2G band allowed channels are:1,6,11
01-01 00:00:18.953  3191  3239 D WifiService: New client listening to asynchronous messages
01-01 00:00:18.955  3191  3191 I WifiScanningService: Registering wifiscanner
01-01 00:00:19.455  3191  3239 D WifiController: DEFERRED_TOGGLE handled
01-01 00:00:19.459  3191  3238 I WifiHW  : USB WiFi device is detected, [148f:5370]
01-01 00:00:19.460  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/compat.ko
01-01 00:00:19.699  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/cfg80211.ko
01-01 00:00:20.552  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/mac80211.ko
01-01 00:00:20.962  3191  3239 D WifiService: New client listening to asynchronous messages
01-01 00:00:21.495  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/rt2x00lib.ko
01-01 00:00:21.726  3191  3239 D WifiService: New client listening to asynchronous messages
01-01 00:00:21.777  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/rt2x00usb.ko
01-01 00:00:21.993  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/rt2800lib.ko
01-01 00:00:22.162  3191  3239 D WifiService: New client listening to asynchronous messages
01-01 00:00:22.220  3191  3238 I WifiHW  : Loading /system/lib/modules/backports/rt2800usb.ko
01-01 00:00:22.684  3191  3238 D WifiHW  : check loading wifi driver is ok...
01-01 00:00:22.684  3191  3238 E WifiHW  : driver loaded
01-01 00:00:22.691  3191  3238 E WifiMonitor: killSupplicant p2pfalse init.svc.wpa_supplicant=unknown init.svc.p2p_supp
licant=unknown
01-01 00:00:22.791  3191  3238 D wifi    : Can not initialize the vendor function pointer table
01-01 00:00:22.791  3191  3238 E WifiNative-HAL: Could not start hal
01-01 00:00:22.792  3191  3238 E WifiStateMachine: Failed to start HAL
01-01 00:00:22.894  3191  3238 D WifiMonitor: startMonitoring(wlan0) with mConnected = false
01-01 00:00:23.572  3191  3238 D WIFI    : Registering NetworkFactory
01-01 00:00:23.572  3191  3238 D WIFI_UT : Registering NetworkFactory
01-01 00:00:23.572  3191  3240 D ConnectivityService: Got NetworkFactory Messenger for WIFI
01-01 00:00:23.572  3191  3240 D ConnectivityService: Got NetworkFactory Messenger for WIFI_UT
01-01 00:00:23.576  3191  3238 D WifiConfigStore: Loading config and enabling all networks
01-01 00:00:23.586  3191  3238 D WifiConfigStore: loaded 0 passpoint configs
01-01 00:00:23.586  3191  3238 E WifiConfigStore: Error parsing configuration: java.io.FileNotFoundException: /data/mis
c/wifi/ipconfig.txt: open failed: ENOENT (No such file or directory)
01-01 00:00:23.589  3191  3238 W WifiConfigStore: Upgrading network 0 to android.uid.system:1000
01-01 00:00:23.597  3191  3238 E WifiConfigStore: readAutoJoinStatus: Error parsing configurationjava.io.FileNotFoundEx
ception: /data/misc/wifi/autojoinconfig.txt: open failed: ENOENT (No such file or directory)
01-01 00:00:23.598  3191  3238 E WifiConfigStore: buildPnoList sortedWifiConfigurations size 1
01-01 00:00:23.598  3191  3238 E WifiConfigStore: found sortedWifiConfigurations : "bag-wifi1"WPA_PSK
01-01 00:00:23.623  3191  3238 D WifiNative-HAL: Setting external_sim to 1
01-01 00:00:23.624  3191  3238 D WifiStateMachine: Setting OUI to DA-A1-19
01-01 00:00:23.629  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:23.631  3191  3238 E WifiStateMachine: setScanAlarm after 3 sec.
01-01 00:00:23.632  3191  3238 D WifiNative-HAL: Failing getSupportedFeatureset because HAL isn't started
01-01 00:00:23.632  3191  3254 E WifiScanningService: could not start HAL
01-01 00:00:23.635  3191  3238 D WIFI    : got request NetworkRequest [ id=1, legacyType=-1, [ Capabilities: INTERNET&N
OT_RESTRICTED&TRUSTED&NOT_VPN] ] with score 0
01-01 00:00:23.635  3191  3238 D WIFI_UT : got request NetworkRequest [ id=1, legacyType=-1, [ Capabilities: INTERNET&N
OT_RESTRICTED&TRUSTED&NOT_VPN] ] with score 0
01-01 00:00:23.637  3191  3238 D WifiConfigStore: No blacklist allowed without epno enabled
01-01 00:00:23.638  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:24.758  3191  3239 D WifiService: New client listening to asynchronous messages
01-01 00:00:24.949  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 100
0 -> 1000
01-01 00:00:24.950  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
01-01 00:00:24.950  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
01-01 00:00:24.956  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
01-01 00:00:25.007  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
01-01 00:00:25.009  3742  3742 I wpa_supplicant: wlan0: SME: Trying to authenticate with 90:b6:86:83:5f:5d (SSID='bag-w
ifi1' freq=2462 MHz)
01-01 00:00:25.049  3742  3742 I wpa_supplicant: wlan0: Trying to associate with 90:b6:86:83:5f:5d (SSID='bag-wifi1' fr
eq=2462 MHz)
01-01 00:00:25.399  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
01-01 00:00:25.405  3191  3238 D ConnectivityService: registerNetworkAgent NetworkAgentInfo{ ni{[type: WIFI[], state: C
ONNECTING/CONNECTING, reason: (unspecified), extra: "bag-wifi1", roaming: false, failover: false, isAvailable: true]}
network{100}  lp{{LinkAddresses: []  Routes: [] DnsAddresses: [] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,20
97152,262144,524288,1048576}}  nc{[ Transports: WIFI Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwi
dth>=1048576Kbps LinkDnBandwidth>=1048576Kbps]}  Score{20}  everValidated{false}  lastValidated{false}  created{false}
lingering{false} explicitlySelected{false} acceptUnvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalD
etected{false} }
01-01 00:00:25.405  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
01-01 00:00:25.405  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, goin
g from null to CONNECTING
01-01 00:00:25.415  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
01-01 00:00:25.426  3191  3238 D WifiStateMachine: Start Dhcp Watchdog 1
01-01 00:00:25.431  3191  3240 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:25.431  3191  3240 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 60
01-01 00:00:25.514  3191  3238 D WifiConfigStore: No blacklist allowed without epno enabled
01-01 00:00:25.514  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, goin
g from CONNECTING to CONNECTED
01-01 00:00:25.517  3191  3238 E WifiStateMachine: Did not find remoteAddress {192.168.43.1} in /proc/net/arp
01-01 00:00:25.992  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:25.995  3191  3240 D ConnectivityService: updateSignalStrengthThresholds: CONNECT, sending [] to NetworkAge
ntInfo [WIFI () - 100]
01-01 00:00:25.995  3191  3240 D ConnectivityService: rematch for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:25.996  3191  3238 D WifiNetworkAgent: NetworkAgent: Received signal strength thresholds: []
01-01 00:00:25.996  3191  3238 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
01-01 00:00:25.996  3191  3240 D ConnectivityService: Switching to new default network: NetworkAgentInfo{ ni{[type: WIF
I[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: "bag-wifi1", roaming: false, failover: false, isAvailabl
e: true]}  network{100}  lp{{InterfaceName: wlan0 LinkAddresses: [fe80::7edd:90ff:fee1:50a9/64,192.168.43.179/24,]  Rou
tes: [fe80::/64 -> :: wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.4
3.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}}  nc{[ Transports: WIFI Capabi
lities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=1048576Kbps LinkDnBandwidth>=1048576Kbps SignalStrengt
h: -33]}  Score{20}  everValidated{false}  lastValidated{false}  created{true} lingering{false} explicitlySelected{fals
e} acceptUnvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalDetected{false} }
01-01 00:00:26.046  3191  3240 D CSLegacyTypeTracker: Sending CONNECTED broadcast for type 1 NetworkAgentInfo [WIFI ()
- 100] isDefaultNetwork=true
01-01 00:00:26.049  3191  3240 D ConnectivityService: notifyType PRECHECK for NetworkAgentInfo [WIFI () - 100]
01-01 00:00:26.052  3191  3191 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
01-01 00:00:26.391  3191  4097 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: Checking http://connectivitycheck.gst
atic.com/generate_204 on "bag-wifi1", connectivitycheck.gstatic.com=216.58.192.227,2607:f8b0:4009:80f::2003
02-26 20:27:20.970  3191  4097 D NetworkMonitor/NetworkAgentInfo [WIFI () - 100]: isCaptivePortal: ret=204 headers={nul
l=[HTTP/1.1 204 No Content], Content-Length=[0], Date=[Mon, 26 Feb 2018 20:27:20 GMT], X-Android-Received-Millis=[15196
76840969], X-Android-Response-Source=[NETWORK 204], X-Android-Selected-Protocol=[http/1.1], X-Android-Sent-Millis=[2643
0]}
02-26 20:27:20.975  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] validation  passed
02-26 20:27:20.975  3191  3240 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:21.243  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:21.249  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:22.917  3191  3240 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:25.918  3191  3240 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 57
02-26 20:27:31.927  3191  3240 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 100] to 60
02-26 20:27:40.005  3191  3238 D WifiStateMachine: L2Connected CMD_START_SCAN source -2 2, 4 -> obsolete
02-26 20:27:41.073  3191  3238 D WifiStateMachine: starting scan for "bag-wifi1"WPA_PSK with 2462
02-26 20:27:41.374  3191  3238 D WifiStateMachine: Lost IP layer provisioning! was: {InterfaceName: wlan0 LinkAddresses
: [fe80::7edd:90ff:fee1:50a9/64,192.168.43.179/24,2600:1008:b111:dc88:7edd:90ff:fee1:50a9/64,2600:1008:b111:dc88:74af:3
337:9c23:460d/64,]  Routes: [fe80::/64 -> :: wlan0,::/0 -> fe80::92b6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 w
lan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.43.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,104857
6,2097152,262144,524288,1048576} now: {InterfaceName: wlan0 LinkAddresses: [fe80::7edd:90ff:fee1:50a9/64,192.168.43.179
/24,2600:1008:b111:dc88:7edd:90ff:fee1:50a9/64,2600:1008:b111:dc88:74af:3337:9c23:460d/64,]  Routes: [fe80::/64 -> :: w
lan0,::/0 -> fe80::92b6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddr
esses: [192.168.43.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}
02-26 20:27:41.377  3191  3238 E WifiConfigStore: SSID temp disabled for  "bag-wifi1"WPA_PSK had autoJoinStatus=0 self
added false ephemeral false
02-26 20:27:41.377  3191  3238 E WifiConfigStore:  message=DHCP FAILURE
02-26 20:27:41.377  3191  3238 E WifiConfigStore: blacklisted "bag-wifi1"WPA_PSK to 0 due to IP config failures, count=
1 disableReason=0 90:b6:86:83:5f:5d ipfail=1
02-26 20:27:41.380  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:41.387  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:41.436  3191  3238 D WifiStateMachine: WifiStateMachine: Leaving Connected state
02-26 20:27:41.436  3191  3238 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-26 20:27:41.436  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:27:41.457  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] EVENT_NETWORK_INFO_CHANGED, goin
g from CONNECTED to DISCONNECTED
02-26 20:27:41.457  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 100] got DISCONNECTED, was satisfying
 2
02-26 20:27:41.459  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:27:41.464  3191  3240 D ConnectivityService: notifyType LOST for NetworkAgentInfo [WIFI () - 100]
02-26 20:27:41.464  3191  3238 D WifiStateMachine: Start Disconnecting Watchdog 1
02-26 20:27:41.465  3191  3238 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-26 20:27:41.510  3191  3240 D CSLegacyTypeTracker: Sending DISCONNECTED broadcast for type 1 NetworkAgentInfo [WIFI
() - 100] isDefaultNetwork=true
02-26 20:27:41.514  3191  3191 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-26 20:27:41.566  3191  3238 D WifiNetworkAgent: NetworkAgent: NetworkAgent channel lost
02-26 20:27:41.566  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:27:42.862  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=5.50 rxSuccessRate=4.50 delta 100
0 -> 994
02-26 20:27:42.862  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:27:42.862  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:27:42.896  3191  3238 D WifiConfigStore: Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:27:42.905  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:27:42.906  3742  3742 I wpa_supplicant: wlan0: SME: Trying to authenticate with 90:b6:86:83:5f:5d (SSID='bag-w
ifi1' freq=2462 MHz)
02-26 20:28:00.796  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=1.38 rxSuccessRate=1.12 delta 100
0 -> 1000
02-26 20:28:00.796  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:28:00.796  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:28:00.801  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:28:00.840  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:28:11.855  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:28:13.144  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.17 rxSuccessRate=0.14 delta 100
0 -> 1000
02-26 20:28:13.145  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:28:13.145  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:28:13.151  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:28:13.190  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:28:26.865  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:28:28.157  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.02 rxSuccessRate=0.02 delta 100
0 -> 1000
02-26 20:28:28.158  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:28:28.158  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:28:28.163  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:28:28.205  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:28:41.875  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:28:43.167  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 100
0 -> 1000
02-26 20:28:43.168  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:28:43.168  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:28:43.175  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:28:43.218  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:28:56.888  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:28:58.174  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 100
0 -> 1000
02-26 20:28:58.175  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:28:58.175  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:28:58.181  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:28:58.220  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:29:11.895  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:29:13.187  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 100
0 -> 1000
02-26 20:29:13.188  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:29:13.188  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:29:13.193  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:29:13.232  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:29:26.905  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:29:28.198  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.00 rxSuccessRate=0.00 delta 100
0 -> 1000
02-26 20:29:28.199  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:29:28.199  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:29:28.204  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:29:28.246  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:29:28.247  3742  3742 I wpa_supplicant: wlan0: SME: Trying to authenticate with 90:b6:86:83:5f:5d (SSID='bag-w
ifi1' freq=2462 MHz)
02-26 20:29:28.285  3742  3742 I wpa_supplicant: wlan0: Trying to associate with 90:b6:86:83:5f:5d (SSID='bag-wifi1' fr
eq=2462 MHz)
02-26 20:29:28.336  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:29:28.339  3191  3238 D ConnectivityService: registerNetworkAgent NetworkAgentInfo{ ni{[type: WIFI[], state: C
ONNECTING/CONNECTING, reason: (unspecified), extra: "bag-wifi1", roaming: false, failover: false, isAvailable: true]}
network{101}  lp{{InterfaceName: wlan0 LinkAddresses: []  Routes: [] DnsAddresses: [] Domains: null MTU: 0 TcpBufferSiz
es: 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{fa
lse}  created{false} lingering{false} explicitlySelected{false} acceptUnvalidated{false} everCaptivePortalDetected{fals
e} lastCaptivePortalDetected{false} }
02-26 20:29:28.339  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:29:28.339  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] EVENT_NETWORK_INFO_CHANGED, goin
g from null to CONNECTING
02-26 20:29:28.347  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:29:28.355  3191  3238 D WifiStateMachine: Start Dhcp Watchdog 2
02-26 20:29:28.358  3191  3240 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 101] to 60
02-26 20:29:28.425  3191  3238 D WifiConfigStore: No blacklist allowed without epno enabled
02-26 20:29:28.425  3191  3240 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:28.426  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] EVENT_NETWORK_INFO_CHANGED, goin
g from CONNECTING to CONNECTED
02-26 20:29:28.430  3191  3238 E WifiStateMachine: Did not find remoteAddress {192.168.43.1} in /proc/net/arp
02-26 20:29:28.914  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:28.917  3191  3240 D ConnectivityService: updateSignalStrengthThresholds: CONNECT, sending [] to NetworkAge
ntInfo [WIFI () - 101]
02-26 20:29:28.917  3191  3240 D ConnectivityService: rematch for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:28.918  3191  3238 D WifiNetworkAgent: NetworkAgent: Received signal strength thresholds: []
02-26 20:29:28.918  3191  3238 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-26 20:29:28.918  3191  3240 D ConnectivityService: Switching to new default network: NetworkAgentInfo{ ni{[type: WIF
I[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: "bag-wifi1", roaming: false, failover: false, isAvailabl
e: true]}  network{101}  lp{{InterfaceName: wlan0 LinkAddresses: [fe80::7edd:90ff:fee1:50a9/64,192.168.43.179/24,]  Rou
tes: [fe80::/64 -> :: wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.4
3.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}}  nc{[ Transports: WIFI Capabi
lities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN LinkUpBandwidth>=1048576Kbps LinkDnBandwidth>=1048576Kbps SignalStrengt
h: -27]}  Score{20}  everValidated{false}  lastValidated{false}  created{true} lingering{false} explicitlySelected{fals
e} acceptUnvalidated{false} everCaptivePortalDetected{false} lastCaptivePortalDetected{false} }
02-26 20:29:28.964  3191  3240 D CSLegacyTypeTracker: Sending CONNECTED broadcast for type 1 NetworkAgentInfo [WIFI ()
- 101] isDefaultNetwork=true
02-26 20:29:28.966  3191  3240 D ConnectivityService: notifyType PRECHECK for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:29.244  3191  3191 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-26 20:29:29.298  3191  4249 D NetworkMonitor/NetworkAgentInfo [WIFI () - 101]: Checking http://connectivitycheck.gst
atic.com/generate_204 on "bag-wifi1", connectivitycheck.gstatic.com=216.58.192.227,2607:f8b0:4009:80f::2003
02-26 20:29:29.388  3191  4249 D NetworkMonitor/NetworkAgentInfo [WIFI () - 101]: isCaptivePortal: ret=204 headers={nul
l=[HTTP/1.1 204 No Content], Content-Length=[0], Date=[Mon, 26 Feb 2018 20:29:29 GMT], X-Android-Received-Millis=[15196
76969387], X-Android-Response-Source=[NETWORK 204], X-Android-Selected-Protocol=[http/1.1], X-Android-Sent-Millis=[1519
676969339]}
02-26 20:29:29.388  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] validation  passed
02-26 20:29:29.388  3191  3240 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:29.422  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:29.430  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:29.436  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:31.360  3191  3240 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:29:41.908  3191  3238 D WifiStateMachine: L2Connected CMD_START_SCAN source -2 11, 12 -> obsolete
02-26 20:29:55.387  3191  3240 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 101] to 57
02-26 20:30:08.436  3191  3238 D WifiStateMachine: starting scan for "bag-wifi1"WPA_PSK with 2462
02-26 20:30:25.310  3191  3238 D WifiStateMachine: Lost IP layer provisioning! was: {InterfaceName: wlan0 LinkAddresses
: [fe80::7edd:90ff:fee1:50a9/64,192.168.43.179/24,2600:1008:b111:dc88:7edd:90ff:fee1:50a9/64,2600:1008:b111:dc88:74af:3
337:9c23:460d/64,]  Routes: [fe80::/64 -> :: wlan0,::/0 -> fe80::92b6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 w
lan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddresses: [192.168.43.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,104857
6,2097152,262144,524288,1048576} now: {InterfaceName: wlan0 LinkAddresses: [fe80::7edd:90ff:fee1:50a9/64,192.168.43.179
/24,2600:1008:b111:dc88:7edd:90ff:fee1:50a9/64,2600:1008:b111:dc88:74af:3337:9c23:460d/64,]  Routes: [fe80::/64 -> :: w
lan0,::/0 -> fe80::92b6:86ff:fe83:5f5d wlan0,192.168.43.0/24 -> 0.0.0.0 wlan0,0.0.0.0/0 -> 192.168.43.1 wlan0,] DnsAddr
esses: [192.168.43.1,] Domains: null MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}
02-26 20:30:25.311  3191  3238 E WifiConfigStore: SSID temp disabled for  "bag-wifi1"WPA_PSK had autoJoinStatus=0 self
added false ephemeral false
02-26 20:30:25.311  3191  3238 E WifiConfigStore:  message=DHCP FAILURE
02-26 20:30:25.311  3191  3238 E WifiConfigStore: blacklisted "bag-wifi1"WPA_PSK to 0 due to IP config failures, count=
1 disableReason=0 90:b6:86:83:5f:5d ipfail=1
02-26 20:30:25.318  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:30:25.326  3191  3240 D ConnectivityService: notifyType IP_CHANGED for NetworkAgentInfo [WIFI () - 101]
02-26 20:30:25.376  3191  3238 D WifiStateMachine: WifiStateMachine: Leaving Connected state
02-26 20:30:25.376  3191  3238 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-26 20:30:25.376  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:30:25.391  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] EVENT_NETWORK_INFO_CHANGED, goin
g from CONNECTED to DISCONNECTED
02-26 20:30:25.392  3191  3240 D ConnectivityService: NetworkAgentInfo [WIFI () - 101] got DISCONNECTED, was satisfying
 2
02-26 20:30:25.397  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:30:25.400  3191  3240 D ConnectivityService: notifyType LOST for NetworkAgentInfo [WIFI () - 101]
02-26 20:30:25.403  3191  3238 D WifiStateMachine: Start Disconnecting Watchdog 2
02-26 20:30:25.404  3191  3238 D WifiNative-HAL: stopRssiMonitoring, cmdId 0
02-26 20:30:25.449  3191  3240 D CSLegacyTypeTracker: Sending DISCONNECTED broadcast for type 1 NetworkAgentInfo [WIFI
() - 101] isDefaultNetwork=true
02-26 20:30:25.452  3191  3191 I EthernetWifiSwitch: action: android.net.conn.CONNECTIVITY_CHANGE
02-26 20:30:25.504  3191  3238 D WifiNetworkAgent: NetworkAgent: NetworkAgent channel lost
02-26 20:30:25.504  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:30:26.794  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=10.00 rxSuccessRate=8.00 delta 10
00 -> 994
02-26 20:30:26.794  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:30:26.794  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:30:26.825  3191  3238 D WifiConfigStore: Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:30:26.834  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:30:26.836  3742  3742 I wpa_supplicant: wlan0: SME: Trying to authenticate with 90:b6:86:83:5f:5d (SSID='bag-w
ifi1' freq=2462 MHz)
02-26 20:30:37.875  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:30:39.166  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=1.25 rxSuccessRate=1.00 delta 100
0 -> 1000
02-26 20:30:39.167  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:30:39.167  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:30:39.173  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:30:39.212  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:30:52.885  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:30:54.174  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.16 rxSuccessRate=0.12 delta 100
0 -> 1000
02-26 20:30:54.175  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:30:54.175  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:30:54.181  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:30:54.224  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:31:07.895  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:31:09.185  3191  3238 D WifiStateMachine: shouldSwitchNetwork  txSuccessRate=0.02 rxSuccessRate=0.02 delta 100
0 -> 1000
02-26 20:31:09.185  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT sup state ScanState my state DisconnectedState nid=
0 roam=3
02-26 20:31:09.185  3191  3238 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "bag-wifi1"WPA_PSK to any
02-26 20:31:09.192  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT will save config -> "bag-wifi1" nid=0
02-26 20:31:09.233  3191  3238 D WifiStateMachine: CMD_AUTO_CONNECT did save config ->  nid=0
02-26 20:31:22.905  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:31:37.920  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:31:52.934  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:32:07.945  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:32:22.960  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:32:37.973  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:32:52.988  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:33:07.995  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:33:23.005  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:33:38.020  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:33:53.033  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:34:08.047  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:34:23.061  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:34:38.065  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:34:53.075  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:35:08.090  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:35:23.103  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:35:38.118  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:35:53.132  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:36:08.146  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:36:23.160  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:36:38.174  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:36:53.187  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:37:08.195  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:37:23.205  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:37:38.215  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:37:53.225  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.
02-26 20:38:08.239  3191  3238 D WifiConfigStore: Retrieve network priorities after PNO.


02-26 20:31:22.905 3191 3238 D WifiConfigStore: Retrieve network priorities after PNO. <-- Turned WiFi Off for 5 minute waiting period
02-26 20:36:38.174 3191 3238 D WifiConfigStore: Retrieve network priorities after PNO. <-- Turned WiFi On, waited 2-3 minutes with no reconnect.

Test 1:
Turn on ODROID with wifi already on
Restart wifi 1 minute after startup
ODROID WiFi reconnects as expected
Turn off wifi, wait 5 minutes
ODROID WiFi no longer reconnects as expected.

Test 2:
Wifi is off
Turn on Odroid
Wait 5 minutes
Turn on Wifi
Odroid Wifi does not attempt to reconnect.

As a note, i waited over 2 hours and it never reconnects.

With the same exact scenarios 5.1 works expected, v3.8 seems to stop working after a few minutes.
User avatar
codewalker
 
Posts: 480
Joined: Mon Feb 25, 2013 11:03 am
languages_spoken: english
ODROIDs: all

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby montaigne » Tue Feb 27, 2018 1:22 pm

No worries : ) Ill download and check it in the morning. (about 10 hours from now)
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby montaigne » Wed Feb 28, 2018 2:16 am

It appears to be working as expected.

I will be waiting for the self-install when available, till then, thanks!
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby codewalker » Wed Feb 28, 2018 4:57 pm

https://wiki.odroid.com/odroid-c2/os_im ... allow_v3.9

montaigne wrote:It appears to be working as expected.

I will be waiting for the self-install when available, till then, thanks!
User avatar
codewalker
 
Posts: 480
Joined: Mon Feb 25, 2013 11:03 am
languages_spoken: english
ODROIDs: all

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby montaigne » Wed Feb 28, 2018 9:43 pm

TY : )
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby montaigne » Thu Mar 01, 2018 12:02 pm

Okay, I am sure you hate me by now. It seems I found another issue that might be simple enough to fix since you are already working on this same module drivers. This works 100% on 5.1 so there is some area to look to.

This is nothing I need to have fixed myself but I did notice today during testing. I confirmed it worked on 5.1 and does not on latest version v3.9

Scenario:
Step 1) Have 2 successful wifi ssid available and previously connected (WiFi A | WiFi B).
Step 2) Connect to WiFi A
Step 3) Wait a few seconds and turn WiFi A off.
Step 4) Nothing happens.

Expected Result: (as it works in 5.1)
Turn off WiFi A
Wifi A disconnects and WiFi B connects.
Turn on WiFi A and WiFi B disconnects and WiFi A connects.
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby codewalker » Thu Mar 15, 2018 2:27 pm

In the case of wifi # 0, if the AP disappears, the rssi value, which was previously connected to the search result, is not scanned and it is not possible to connect to another AP.
User avatar
codewalker
 
Posts: 480
Joined: Mon Feb 25, 2013 11:03 am
languages_spoken: english
ODROIDs: all

Re: Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Net

Unread postby montaigne » Fri Mar 16, 2018 10:35 pm

I am only comparing the behavior of v6 vs 5.1. I am not sure of the reason why they are behaving differently.
In 5.1, it falls back to a previous wifi while main wifi disappears and reconnects when it re-appears, whereas in 6 it does not.
montaigne
 
Posts: 54
Joined: Mon Mar 28, 2016 8:56 am
languages_spoken: english
ODROIDs: c1+,c2


Return to Android

Who is online

Users browsing this forum: No registered users and 3 guests