Android 6.0.1: Module0 WiFi Not Auto Reconnecting to Network

Moderators: mdrjr, odroid

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: 46
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: 46
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: 460
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: 46
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: 46
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: 26338
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: 46
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: 26338
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: 46
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 5 guests