10-19 00:19:21.336 791 894 E WiFiServiceImpl : CONNECT nid=-1 uid=1000 name=android.uid.system:1000 10-19 00:19:21.336 791 894 D WifiService: Connect with config- DSBLE ID: 0 SSID: "$SSID" PROVIDER-NAME: null BSSID: any FQDN: null PRIO: 5 10-19 00:19:21.336 791 894 D WifiService: numIpConfigFailures 10 10-19 00:19:21.336 791 894 D WifiService: autoJoinStatus 128 10-19 00:19:21.336 791 894 D WifiService: disableReason 2 10-19 00:19:21.336 791 894 D WifiService: numAssociation 9 10-19 00:19:21.336 791 894 D WifiService: KeyMgmt: WPA_PSK Protocols: WPA RSN 10-19 00:19:21.336 791 894 D WifiService: AuthAlgorithms: 10-19 00:19:21.336 791 894 D WifiService: PairwiseCiphers: TKIP CCMP 10-19 00:19:21.336 791 894 D WifiService: GroupCiphers: TKIP CCMP 10-19 00:19:21.336 791 894 D WifiService: PSK: * 10-19 00:19:21.336 791 894 D WifiService: Enterprise config: 10-19 00:19:21.336 791 894 D WifiService: password NULL 10-19 00:19:21.336 791 894 D WifiService: client_cert NULL 10-19 00:19:21.336 791 894 D WifiService: engine 0 10-19 00:19:21.336 791 894 D WifiService: anonymous_identity NULL 10-19 00:19:21.336 791 894 D WifiService: identity NULL 10-19 00:19:21.336 791 894 D WifiService: domain_suffix_match NULL 10-19 00:19:21.336 791 894 D WifiService: phase2 NULL 10-19 00:19:21.336 791 894 D WifiService: altsubject_match NULL 10-19 00:19:21.336 791 894 D WifiService: subject_match NULL 10-19 00:19:21.336 791 894 D WifiService: ca_cert NULL 10-19 00:19:21.336 791 894 D WifiService: phase1 NULL 10-19 00:19:21.336 791 894 D WifiService: key_id NULL 10-19 00:19:21.336 791 894 D WifiService: engine_id NULL 10-19 00:19:21.336 791 894 D WifiService: eap NULL 10-19 00:19:21.336 791 894 D WifiService: IP config: 10-19 00:19:21.336 791 894 D WifiService: IP assignment: DHCP 10-19 00:19:21.336 791 894 D WifiService: Proxy settings: NONE 10-19 00:19:21.336 791 894 D WifiService: autoJoinBSSID=any 10-19 00:19:21.336 791 894 D WifiService: blackListed: 105sec cuid=1000 cname=android.uid.system:1000 luid=1000 lname=android.uid.system:1000 lcuid=1000 userApproved=USER_APPROVED noInternetAccessExpected=false 10-19 00:19:21.336 791 894 D WifiService: lastConnectionFailure: 105sec roamingFailureBlackListTimeMilli: 1000 10-19 00:19:21.336 791 894 D WifiService: triggeredLow: 0 triggeredBad: 0 triggeredNotHigh: 0 10-19 00:19:21.336 791 894 D WifiService: ticksLow: 0 ticksBad: 0 ticksNotHigh: 0 10-19 00:19:21.336 791 894 D WifiService: triggeredJoin: 2 10-19 00:19:21.336 791 894 D WifiService: autoJoinBailedDueToLowRssi: false 10-19 00:19:21.336 791 894 D WifiService: autoJoinUseAggressiveJoinAttemptThreshold: 0 10-19 00:19:21.337 791 893 D WifiStateMachine: CONNECT_NETWORK updating existing config with id=0 configKey="$SSID"WPA_PSK 10-19 00:19:21.351 278 824 D audio_hw_primary: out_set_parameters: enter: usecase(1: low-latency-playback) kvpairs: routing=2 10-19 00:19:21.385 791 893 D WifiConfigStore: Setting BSSID for "$SSID"WPA_PSK to any 10-19 00:19:21.403 791 893 D WifiStateMachine: CONNECT_NETWORK id=0 config="$SSID" cnid=0 supstate=ScanState my state DisconnectedState uid = 1000 10-19 00:19:21.403 791 893 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "$SSID"WPA_PSK to any 10-19 00:19:21.412 791 893 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "$SSID"WPA_PSK to any 10-19 00:19:21.420 791 893 D WifiAutoJoinController : updateConfigurationHistory found 1 networks 10-19 00:19:21.420 791 893 E WifiConfigStore: rewrite network history for "$SSID"WPA_PSK 10-19 00:19:21.422 791 893 E WifiConfigStore: buildPnoList sortedWifiConfigurations size 1 10-19 00:19:21.422 791 893 E WifiConfigStore: found sortedWifiConfigurations : "$SSID"WPA_PSK 10-19 00:19:22.162 1360 1360 I wpa_supplicant: wlan0: Trying to associate with $MAC (SSID='$SSID' freq=5785 MHz) 10-19 00:19:22.266 1360 1360 I wpa_supplicant: wlan0: Associated with $MAC 10-19 00:19:22.297 1360 1360 I wpa_supplicant: wlan0: WPA: Key negotiation completed with $MAC [PTK=CCMP GTK=CCMP] 10-19 00:19:22.297 1360 1360 I wpa_supplicant: wlan0: CTRL-EVENT-CONNECTED - Connection to $MAC completed [id=0 id_str=] 10-19 00:19:22.317 791 893 D ConnectivityService: registerNetworkAgent NetworkAgentInfo{ ni{[type: WIFI[], state: CONNECTING/CONNECTING, reason: (unspecified), extra: "$SSID", roaming: false, failover: false, isAvailable: true]} network{124} lp{{InterfaceName: wlan0 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} } 10-19 00:19:22.317 791 893 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "$SSID"WPA_PSK to any 10-19 00:19:22.317 791 895 D ConnectivityService: NetworkAgentInfo [WIFI () - 124] EVENT_NETWORK_INFO_CHANGED, going from null to CONNECTING 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: invalid interface 'wlan0': 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: java.lang.IllegalArgumentException: invalid interface 'wlan0': 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at android.net.IpReachabilityMonitor.(IpReachabilityMonitor.java:150) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at com.android.server.wifi.WifiStateMachine$L2ConnectedState.enter(WifiStateMachine.java:8213) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at com.android.internal.util.StateMachine$SmHandler.invokeEnterMethods(StateMachine.java:1009) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at com.android.internal.util.StateMachine$SmHandler.performTransitions(StateMachine.java:855) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at com.android.internal.util.StateMachine$SmHandler.handleMessage(StateMachine.java:801) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at android.os.Handler.dispatchMessage(Handler.java:102) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at android.os.Looper.loop(Looper.java:148) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at android.os.HandlerThread.run(HandlerThread.java:61) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: Caused by: java.net.SocketException 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at java.net.NetworkInterface.rethrowAsSocketException(NetworkInterface.java:248) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at java.net.NetworkInterface.readIfInet6Lines(NetworkInterface.java:136) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at java.net.NetworkInterface.getByName(NetworkInterface.java:112) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at android.net.IpReachabilityMonitor.(IpReachabilityMonitor.java:147) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: ... 7 more 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: Caused by: java.io.FileNotFoundException: /proc/net/if_inet6: open failed: ENOENT (No such file or directory) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at libcore.io.IoBridge.open(IoBridge.java:452) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at libcore.io.IoUtils$FileReader.(IoUtils.java:207) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at libcore.io.IoUtils.readFileAsString(IoUtils.java:114) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at java.net.NetworkInterface.readIfInet6Lines(NetworkInterface.java:134) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: ... 9 more 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: Caused by: android.system.ErrnoException: open failed: ENOENT (No such file or directory) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at libcore.io.Posix.open(Native Method) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at libcore.io.BlockGuardOs.open(BlockGuardOs.java:186) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: at libcore.io.IoBridge.open(IoBridge.java:438) 10-19 00:19:22.338 791 893 E Failed to create IpReachabilityMonitor: ... 12 more 10-19 00:19:22.349 791 893 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "$SSID"WPA_PSK to any 10-19 00:19:22.362 274 790 D CommandListener: Setting iface cfg 10-19 00:19:22.363 791 2311 E DhcpClient: Can't determine ifindex or MAC address for wlan0 10-19 00:19:22.364 791 893 D WifiStateMachine: Start Dhcp Watchdog 24 10-19 00:19:22.377 791 895 D ConnectivityService: notifyType CAP_CHANGED for NetworkAgentInfo [WIFI () - 124] 10-19 00:19:22.378 791 895 D ConnectivityService: updateNetworkScore for NetworkAgentInfo [WIFI () - 124] to 60 10-19 00:19:22.461 791 893 E native : do suspend false 10-19 00:19:22.470 791 893 E WifiConfigStore: SSID temp disabled for "$SSID"WPA_PSK had autoJoinStatus=0 self added false ephemeral false 10-19 00:19:22.470 791 893 E WifiConfigStore: message=DHCP FAILURE 10-19 00:19:22.470 791 893 E WifiConfigStore: blacklisted "$SSID"WPA_PSK to 0 due to IP config failures, count=1 disableReason=2 $MAC ipfail=22 10-19 00:19:22.473 1360 1360 I wpa_supplicant: wlan0: CTRL-EVENT-DISCONNECTED bssid=$MAC reason=3 locally_generated=1 10-19 00:19:22.474 791 893 D WifiNative-HAL: stopRssiMonitoring, cmdId 0 10-19 00:19:22.475 791 893 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "$SSID"WPA_PSK to any 10-19 00:19:22.486 274 790 D CommandListener: Clearing all IP addresses on wlan0 10-19 00:19:22.489 791 895 D ConnectivityService: NetworkAgentInfo [WIFI () - 124] EVENT_NETWORK_INFO_CHANGED, going from CONNECTING to DISCONNECTED 10-19 00:19:22.489 791 895 D ConnectivityService: NetworkAgentInfo [WIFI () - 124] got DISCONNECTED, was satisfying 0 10-19 00:19:22.490 791 895 E NetworkStats: problem reading network stats 10-19 00:19:22.490 791 895 E NetworkStats: java.lang.IllegalStateException: java.io.IOException: Failed to parse network stats 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.server.NetworkManagementService.getNetworkStatsUidDetail(NetworkManagementService.java:1847) 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.server.net.NetworkStatsService.getNetworkStatsUidDetail(NetworkStatsService.java:1255) 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.server.net.NetworkStatsService.recordSnapshotLocked(NetworkStatsService.java:971) 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.server.net.NetworkStatsService.performPollLocked(NetworkStatsService.java:1035) 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.server.net.NetworkStatsService.updateIfacesLocked(NetworkStatsService.java:903) 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.server.net.NetworkStatsService.updateIfaces(NetworkStatsService.java:880) 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.server.net.NetworkStatsService.forceUpdateIfaces(NetworkStatsService.java:719) 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.server.ConnectivityService.notifyIfacesChanged(ConnectivityService.java:4789) 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.server.ConnectivityService.handleAsyncChannelDisconnected(ConnectivityService.java:2182) 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.server.ConnectivityService.-wrap4(ConnectivityService.java) 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.server.ConnectivityService$NetworkStateTrackerHandler.handleMessage(ConnectivityService.java:1936) 10-19 00:19:22.490 791 895 E NetworkStats: at android.os.Handler.dispatchMessage(Handler.java:102) 10-19 00:19:22.490 791 895 E NetworkStats: at android.os.Looper.loop(Looper.java:148) 10-19 00:19:22.490 791 895 E NetworkStats: at android.os.HandlerThread.run(HandlerThread.java:61) 10-19 00:19:22.490 791 895 E NetworkStats: Caused by: java.io.IOException: Failed to parse network stats 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.internal.net.NetworkStatsFactory.readNetworkStatsDetailInternal(NetworkStatsFactory.java:248) 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.internal.net.NetworkStatsFactory.readNetworkStatsDetail(NetworkStatsFactory.java:191) 10-19 00:19:22.490 791 895 E NetworkStats: at com.android.server.NetworkManagementService.getNetworkStatsUidDetail(NetworkManagementService.java:1845) 10-19 00:19:22.490 791 895 E NetworkStats: ... 13 more 10-19 00:19:22.493 791 893 E WifiConfigStore: saveWifiConfigBSSID Setting BSSID for "$SSID"WPA_PSK to any 10-19 00:19:22.503 791 895 D ConnectivityService: notifyType LOST for NetworkAgentInfo [WIFI () - 124] 10-19 00:19:22.504 791 895 E ConnectivityService: EVENT_NETWORK_INFO_CHANGED from unknown NetworkAgent 10-19 00:19:22.508 791 893 D WifiStateMachine: Start Disconnecting Watchdog 24 10-19 00:19:22.509 791 893 D WifiNative-HAL: stopRssiMonitoring, cmdId 0 10-19 00:19:22.511 274 790 D CommandListener: Clearing all IP addresses on wlan0 10-19 00:19:22.514 791 893 D WifiNetworkAgent: NetworkAgent: NetworkAgent channel lost