tinus0828 / rowboat

Automatically exported from code.google.com/p/rowboat
0 stars 0 forks source link

Android cofiguring problem #31

Open GoogleCodeExporter opened 9 years ago

GoogleCodeExporter commented 9 years ago
Hi,all
We are tring to port USB wifi vt6656 to rowboat Android,we can use 
wireless tools(which contain iwconfig,iwlist,etc.) configuring wifi 
successfully,by using this way we can connect the internet 
successfully.However,when we tried to use Android UI(wifi setting) to 
configure it,it can find ap signal,but just a second later,the system 
crashed,the logcat message shows below:

com.android.settings/com.android.settings.WirelessSettings paused=false}
I/ActivityManager(  771): Starting activity: Intent { 
act=android.intent.action.MAIN 
cmp=com.android.settings/.wifi.WifiSettings }
I/ActivityManager(  771): Displayed activity 
com.android.settings/.wifi.WifiSettings: 327 ms (total 327 ms)
I/WindowManager(  771): Checking dispatch to: Window{49ca9e40 StatusBar 
paused=false}
I/WindowManager(  771): Checking dispatch to: Window{49cdd180 
StatusBarExpanded paused=false}
I/WindowManager(  771): Not visible!
I/WindowManager(  771): Checking dispatch to: Window{49cc8180 TrackingView 
paused=false}
I/WindowManager(  771): Not visible!
I/WindowManager(  771): Checking dispatch to: Window{49c59978 
com.android.settings/com.android.settings.wifi.WifiSettings paused=false}
D/SettingsWifiEnabler(  893): Received wifi state changed from Disabled to 
Enabling
VIA Networking Wireless LAN USB Driver Ver. 1.20.03
VIA Networking Wireless LAN USB Driver Ver. 1.20.03
Copyright (c) 2004 VIA Networking Technologies, Inc.
eth1 (vntwusb): not using net_device_ops yet
vntwpa (vntwusb): not using net_device_ops yet
usb 1-1.1: reset high speed USB device using musb_hdrc and address 3
usbcore: registered new interface driver vntwusb
E/WifiHW  (  771): Wi-Fi has be loaded...
init: no such service 'ifcfg_vt6656'
D/dalvikvm(  771): GC freed 9820 objects / 470256 bytes in 156ms
Config_FileOperation file Not exist
Zone=[2][E][U]!!
Antenna AUX&MAIN all available!
E/WifiHW  (  771): Unable to open connection to supplicant 
on "/data/system/wpa_supplicant/eth1": Connection refused
I/wpa_supplicant(  907): CTRL-EVENT-STATE-CHANGE id=-1 state=2
W/wpa_supplicant(  907): Failed to initiate AP scan.
D/WifiService(  771): ACTION_BATTERY_CHANGED pluggedType: 1
V/WifiHW  (  771): Not connected to wpa_supplicant - "LIST_NETWORKS" 
command dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "AP_SCAN 2" command 
dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "DRIVER SCAN-ACTIVE" 
command dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "SCAN" command 
dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "DRIVER SCAN-PASSIVE" 
command dropped.
D/SettingsWifiEnabler(  893): Received wifi state changed from Enabling to 
Enabled
V/WifiHW  (  771): Not connected to wpa_supplicant - "LIST_NETWORKS" 
command dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "BLACKLIST clear" 
command dropped.
I/wpa_supplicant(  907): CTRL-EVENT-SCAN-RESULTS  Ready
I/wpa_supplicant(  907): Trying to assoAP(BSS) finding:Found a AP(BSS)..
ciate with 00:0a:eb:d0:e8:52 (SSID='TP-LINK' freq=2437 MHz)
I/wpa_supplicant(  907): CTRL-EVENT-STATE-CHANGE id=-1 state=3
802.11 Authen (OPEN) Successful.
Association Successful, AID=4.
Link with AP(SSID): TP-LINK
I/wpa_supplicant(  907): CTRL-EVENT-STATE-CHANGE id=0 state=4
I/wpa_supplicant(  907): Associated with 00:0a:eb:d0:e8:52
I/wpa_supplicant(  907): CTRL-EVENT-STATE-CHANGE id=0 state=7
I/wpa_supplicant(  907): CTRL-EVENT-CONNECTED - Connection to 
00:0a:eb:d0:e8:52 completed (auth) [id=0 id_str=]
V/WifiHW  (  771): Not connected to wpa_supplicant - "AP_SCAN 2" command 
dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "DRIVER SCAN-ACTIVE" 
command dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "SCAN" command 
dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "DRIVER SCAN-PASSIVE" 
command dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "AP_SCAN 2" command 
dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "DRIVER SCAN-ACTIVE" 
command dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "SCAN" command 
dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "DRIVER SCAN-PASSIVE" 
command dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "AP_SCAN 2" command 
dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "DRIVER SCAN-ACTIVE" 
command dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "SCAN" command 
dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "DRIVER SCAN-PASSIVE" 
command dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "AP_SCAN 2" command 
dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "DRIVER SCAN-ACTIVE" 
command dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "SCAN" command 
dropped.
V/WifiHW  (  771): Not connected to wpa_supplicant - "DRIVER SCAN-PASSIVE" 
command dropped.
E/SettingsWifiLayer(  893): Unable to scan for networks
I/NotificationService(  771): enqueueToast pkg=com.android.settings 
callback=android.app.ITransientNotification$Stub$Proxy@49cf4e20 duration=1
W/ActivityManager(  771): Unable to start service Intent { 
act=android.bluetooth.IBluetoothHeadset }: not found
E/BluetoothHeadset(  771): Could not bind to Bluetooth Headset Service
V/WifiStateTracker(  771): Connection to supplicant established, 
state=COMPLETED
W/BluetoothHeadset(  771): Proxy not attached to service
D/WifiStateTracker(  771): DhcpHandler: DHCP request started
D/dalvikvm(  893): GC freed 3909 objects / 219888 bytes in 261ms
I/ARMAssembler(  771): generated 
scanline__00000077:03515104_00001A01_00000000 [ 46 ipp] (68 ins) at 
[0x25e4a8:0x25e5b8] in 427246 ns
I/wpa_supplicant(  907): CTRL-EVENT-SCAN-RESULTS  Ready
I/WindowManager(  771): Checking dispatch to: Window{49ca9e40 StatusBar 
paused=false}
I/WindowManager(  771): Checking dispatch to: Window{49cdd180 
StatusBarExpanded paused=false}
I/WindowManager(  771): Not visible!
I/WindowManager(  771): Checking dispatch to: Window{49cc8180 TrackingView 
paused=false}
I/WindowManager(  771): Not visible!
I/WindowManager(  771): Checking dispatch to: Window{49c59978 
com.android.settings/com.android.settings.wifi.WifiSettings paused=false}
D/dalvikvm(  893): Trying to load lib /system/lib/libcerttool_jni.so 0x0
D/dalvikvm(  893): Added shared lib /system/lib/libcerttool_jni.so 0x0
I/ARMAssembler(  771): generated 
scanline__00000077:03515104_00000000_00000000 [ 27 ipp] (41 ins) at 
[0x262a70:0x262b14] in 305175 ns
I/ARMAssembler(  771): generated 
scanline__00000077:03515104_00001001_00000000 [ 64 ipp] (84 ins) at 
[0x263298:0x2633e8] in 427246 ns
I/WindowManager(  771): Checking dispatch to: Window{49ca9e40 StatusBar 
paused=false}
I/WindowManager(  771): Checking dispatch to: Window{49cdd180 
StatusBarExpanded paused=false}
I/WindowManager(  771): Not visible!
I/WindowManager(  771): Checking dispatch to: Window{49cc8180 TrackingView 
paused=false}
I/WindowManager(  771): Not visible!
I/WindowManager(  771): Checking dispatch to: Window{49d2b8e0 Connect to 
TP-LINK paused=false}
I/wpa_supplicant(  907): CTRL-EVENT-STATE-CHANGE id=0 state=0
V/WifiMonitor(  771): Event [CTRL-EVENT-STATE-CHANGE id=0 state=0]
V/WifiStateTracker(  771): ChangUnable to handle kernel NULL pointer 
dereference at virtual address 00000001
ing supplicant state: COMPLETED pgd = c73cc000
==> DISCONNECTED
D/WifiStateTra[00000001] *pgd=87096031cker(  771): Deconfiguring inter, 
*pte=00000000face and stopping DHCP
, *ppte=00000000
Internal error: Oops: 17 [#1]
Modules linked in: vntwusb
CPU: 0    Not tainted  (2.6.29-rc3-omap1 #23)
PC is at tcp_v4_nuke_addr+0x80/0xb8
LR is at tcp_v4_nuke_addr+0x2c/0xb8
pc : [<c02e09b4>]    lr : [<c02e0960>]    psr: 20000013
sp : c5c83e18  ip : c5c83e18  fp : c5c83e44
r10: 0000006e  r9 : c04d1c30  r8 : c04d0efc
r7 : 00000000  r6 : 00000000  r5 : 00000001  r4 : 00000001
r3 : c7120000  r2 : c5c82000  r1 : 00000023  r0 : 00000000
Flags: nzCv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment user
Control: 10c5387d  Table: 873cc019  DAC: 00000015
Process ConnectivityThr (pid: 801, stack limit = 0xc5c822e0)
Stack: (0xc5c83e18 to 0xc5c84000)
3e00:                                                       c5c83e44 
00008939 
3e20: 00000000 00000001 c5fa70a0 c5c83e68 c5dbf400 c5fa70ac c5c83eb4 
c5c83e48 
3e40: c02ec550 c02e0940 00000008 c057fac0 52707d54 00000000 31687465 
00000000 
3e60: 00000000 00000000 00000002 00000000 00000000 00000000 00000002 
00000000 
3e80: 00000000 00000000 c5c83eb4 52707d54 00008939 52707d54 c7313f20 
52707d54 
3ea0: c5c82000 44105ea8 c5c83ecc c5c83eb8 c02ed58c c02ebe04 00000002 
c7313f20 
3ec0: c5c83eec c5c83ed0 c029f9f0 c02ed4b8 00000000 c7313f20 00008939 
52707d54 
3ee0: c5c83f0c c5c83ef0 c00b633c c029f808 c5c83fac 00000043 c7313f20 
00008939 
3f00: c5c83f7c c5c83f10 c00b6858 c00b6310 c5c83f5c c5c83f20 c02a003c 
00000018 
3f20: 00000000 00000000 fffffffe 00000000 c5c83f64 c5c83f40 c00731a0 
c004f2c4 
3f40: c729e01c c7132040 00000015 c70d4c00 c04583c0 00000043 52707d54 
00008939 
3f60: c7313f20 c0034044 c5c82000 44105ea8 c5c83fa4 c5c83f80 c00b68fc 
c00b63b8 
3f80: c02a0d20 00000001 adb040c8 52707d64 52707d54 00000036 00000000 
c5c83fa8 
3fa0: c0033ec0 c00b68c8 adb040c8 52707d64 00000043 00008939 52707d54 
00000002 
3fc0: adb040c8 52707d64 52707d54 00000036 52707da8 44105eb8 44105ea8 
ad040101 
3fe0: adb040dc 52707d38 afe1fbc5 afe0c71c 00000010 00000043 ff0020ff 
ff0100ff 
Backtrace: 
[<c02e0934>] (tcp_v4_nuke_addr+0x0/0xb8) from [<c02ec550>] 
(devinet_ioctl+0x758/0x7cc)
[<c02ebdf8>] (devinet_ioctl+0x0/0x7cc) from [<c02ed58c>] 
(inet_ioctl+0xe0/0x110)
[<c02ed4ac>] (inet_ioctl+0x0/0x110) from [<c029f9f0>] 
(sock_ioctl+0x1f4/0x24c)
 r4:c7313f20
[<c029f7fc>] (sock_ioctl+0x0/0x24c) from [<c00b633c>] (vfs_ioctl+0x38/0x7c)
 r6:52707d54 r5:00008939 r4:c7313f20
[<c00b6304>] (vfs_ioctl+0x0/0x7c) from [<c00b6858>] 
(do_vfs_ioctl+0x4ac/0x510)
 r6:00008939 r5:c7313f20 r4:00000043
[<c00b63ac>] (do_vfs_ioctl+0x0/0x510) from [<c00b68fc>] 
(sys_ioctl+0x40/0x64)
[<c00b68bc>] (sys_ioctl+0x0/0x64) from [<c0033ec0>] 
(ret_fast_syscall+0x0/0x2c)
 r7:00000036 r6:52707d54 r5:52707d64 r4:adb040c8
Code: ebffbdd8 e5955000 e2554000 0a000005 (e4143008) 
Kernel panic - not syncing: Fatal exception in interrupt

Original issue reported on code.google.com by jinkui...@gmail.com on 16 May 2010 at 2:33

GoogleCodeExporter commented 9 years ago
Have more or less the same issue with a rt2870usb.
Crash occurs either when android-UI tryes to connect to a AP or tries to unload 
the
module.
I think it is either the way that android talks to the module that isn't 
completly
implemented in the driver or something in the tcp_v4 itself for our platform.

*******
V/WifiMonitor(  787): Event [Authentication with 00:12:44:b2:b4:21 timed out.]
V/WifiMonitor(  787): Event [CTRL-EVENT-STATE-CHANGE id=0 state=0]
V/WifiStateTracker(  787): Changing supplicant state: ASSOCIATING ==> 
DISCONNECTED
V/WifiMonitor(  787): Event [CTRL-EVENT-STATE-CHANGE id=-1 state=2]
V/WifiStateTracker(  787): Changing supplicant state: DISCONNECTED ==> SCANNING
D/wpa_supplicant(  841): AWEXT: wpa_driver_priv_driver_cmd SCAN-ACTIVE
D/wpa_supplicant(  841): Scan Active command
D/wpa_supplicant(  841): Ongoing Scan action...
D/wpa_supplicant(  841): AWEXT: wpa_driver_priv_driver_cmd SCAN-PASSIVE
D/wpa_supplicant(  841): Scan Passive command
===>rt_ioctl_giwscan. 7(7) BSS returned, data->length = 973
Unable to handle kernel NULL pointer dereference at virtual address 00000001
pgd = c70e0000
[00000001] *pgd=87777031, *pte=00000000, *ppte=00000000
Internal error: Oops: 17 [#1]
Modules linked in: rt2870sta
CPU: 0    Not tainted  (2.6.29-rc3-omap1-g9cdf623-dirty #5)
PC is at tcp_v4_nuke_addr+0x7c/0xb8
LR is at tcp_v4_nuke_addr+0x1c/0xb8
pc : [<c029a8b8>]    lr : [<c029a858>]    psr: 20000013
sp : c7b9be20  ip : c7b9be20  fp : c7b9be3c
r10: c7b426ac  r9 : 00000000  r8 : c7b426a0
r7 : 00000000  r6 : 00000001  r5 : 00000000  r4 : 00000001
r3 : c7060000  r2 : 00000100  r1 : c7b9be50  r0 : 00000000
Flags: nzCv  IRQs on  FIQs on  Mode SVC_32  ISA ARM  Segment user
Control: 10c5387d  Table: 870e0019  DAC: 00000015
Process ConnectivityThr (pid: 836, stack limit = 0xc7b9a2e0)
Stack: (0xc7b9be20 to 0xc7b9c000)
be20: 00008939 00000000 c7cecc00 00000001 c7b9beac c7b9be40 c02a5d44 c029a848 
be40: c0034500 00000000 c7b9be60 467bdd34 00306172 00000000 00000000 00000000 
be60: 00000002 00000000 00000000 00000000 00000002 00000000 00000000 00000000 
be80: 0000003c 00008939 467bdd34 c0251dbc 00000020 c002dfa8 c7b9a000 00000000 
bea0: c7b9bec4 c7b9beb0 c02a6f1c c02a5690 c1c5d8c0 00008939 c7b9bee4 c7b9bec8 
bec0: c0251fb4 c02a6e4c 467bdd34 c7fb4720 00008939 c0251dbc c7b9bf04 c7b9bee8 
bee0: c00b2014 c0251dc8 c1c5d8e8 467bdd34 c7fb4720 00008939 c7b9bf7c c7b9bf08 
bf00: c00b2538 c00b1fec c7fb4720 c1c5d8c0 c7b9bf5c c7b9bf20 c02525d8 c00a73c8 
bf20: c0340ce0 00000020 c1c5d8c0 00000000 00000000 c03ccd02 00000020 00000000 
bf40: c1c5d8c0 00000119 c002dfa8 00000000 c7b9bf84 c7b9bf60 c7fb4720 467bdd34 
bf60: 00008939 00000020 c002dfa8 c7b9a000 c7b9bfa4 c7b9bf80 c00b25bc c00b2088 
bf80: c02532d0 00000001 adb040c8 00408568 afe44c20 00000036 00000000 c7b9bfa8 
bfa0: c002de00 c00b2588 adb040c8 00408568 00000020 00008939 467bdd34 467bdd2c 
bfc0: adb040c8 00408568 afe44c20 00000036 467bdd88 427a3e9c 427a3e88 002d4aa0 
bfe0: adb040dc 467bdd10 afe20741 afe0cb0c 00000010 00000020 486f6964 77647261 
Backtrace: 
[<c029a83c>] (tcp_v4_nuke_addr+0x0/0xb8) from [<c02a5d44>] 
(devinet_ioctl+0x6c0/0x758)
 r7:00000001 r6:c7cecc00 r5:00000000 r4:00008939
[<c02a5684>] (devinet_ioctl+0x0/0x758) from [<c02a6f1c>] (inet_ioctl+0xdc/0x10c)
[<c02a6e40>] (inet_ioctl+0x0/0x10c) from [<c0251fb4>] (sock_ioctl+0x1f8/0x248)
 r4:00008939 r3:c1c5d8c0
[<c0251dbc>] (sock_ioctl+0x0/0x248) from [<c00b2014>] (vfs_ioctl+0x34/0x74)
 r6:c0251dbc r5:00008939 r4:c7fb4720 r3:467bdd34
[<c00b1fe0>] (vfs_ioctl+0x0/0x74) from [<c00b2538>] (do_vfs_ioctl+0x4bc/0x500)
 r6:00008939 r5:c7fb4720 r4:467bdd34 r3:c1c5d8e8
[<c00b207c>] (do_vfs_ioctl+0x0/0x500) from [<c00b25bc>] (sys_ioctl+0x40/0x64)
 r9:c7b9a000 r8:c002dfa8 r7:00000020 r6:00008939 r5:467bdd34
r4:c7fb4720
[<c00b257c>] (sys_ioctl+0x0/0x64) from [<c002de00>] (ret_fast_syscall+0x0/0x2c)
 r7:00000036 r6:afe44c20 r5:00408568 r4:adb040c8
Code: e5966000 e3560000 0a000006 e1a04006 (e4143008) 
Kernel panic - not syncing: Fatal exception in interrupt

Original comment by rough...@gmail.com on 18 May 2010 at 1:03

GoogleCodeExporter commented 9 years ago
I did manage to get the kernel panic to go away.
What I did was copy the function tcp_v4_nuke_addr from a newer kernel. I don't 
like
it but now it dosn't panic out.
I did also set Wi-Fi sleep policy to "Never" in advanced for wifi, this might 
have
had something to do with it but without the updated function it would panic 
anyhow.

changes in kernel/net/ipv4/tcp_ipv4.c
Commented out the existing tcp_v4_nuke_addr()
and added:
void tcp_v4_nuke_addr(__u32 saddr)
{
    unsigned int bucket;

    for (bucket = 0; bucket < tcp_hashinfo.ehash_size; bucket++) {
        struct hlist_nulls_node *node;
        struct sock *sk;
        spinlock_t *lock = inet_ehash_lockp(&tcp_hashinfo, bucket);

restart:
        spin_lock_bh(lock);
        sk_nulls_for_each(sk, node, &tcp_hashinfo.ehash[bucket].chain) {
            struct inet_sock *inet = inet_sk(sk);

            if (inet->rcv_saddr != saddr)
                continue;
            if (sysctl_ip_dynaddr && sk->sk_state == TCP_SYN_SENT)
                continue;
            if (sock_flag(sk, SOCK_DEAD))
                continue;

            sock_hold(sk);
            spin_unlock_bh(lock);

            local_bh_disable();
            bh_lock_sock(sk);
            sk->sk_err = ETIMEDOUT;
            sk->sk_error_report(sk);

            tcp_done(sk);
            bh_unlock_sock(sk);
            local_bh_enable();
            sock_put(sk);

            goto restart;
        }
        spin_unlock_bh(lock);
    }
}
----------------------

I do really feel that this is not a solution but just a fix to keep the kernel 
from
panicing.

Original comment by rough...@gmail.com on 18 May 2010 at 1:58

GoogleCodeExporter commented 9 years ago
[deleted comment]
GoogleCodeExporter commented 9 years ago
Hi,everyone,I also want to port usb wifi  driver to Rowboat Androd on 
beagleboard and 
use Android UI to get connet to Internet,but I have no ideal on how to do 
it.Can anyone 
give me some ideal?My wifi modules is rt2870.

Original comment by dreamdou...@126.com on 19 May 2010 at 11:05

GoogleCodeExporter commented 9 years ago
here is a pretty straight forward "guide" 
http://blog.linuxconsulting.ro/2010/04/porting-wifi-drivers-to-android.html

Original comment by rough...@gmail.com on 20 May 2010 at 1:16

GoogleCodeExporter commented 9 years ago
Thanks,roughack.The Web page that you gave is helpfull for me.But I have a 
question 
about the Web page.Can the patch(According to the web page you gave,it make 
driver 
work with Android custom wpa_supplicant commands and SIOCSIWPRIV ioctl) gave by 
the 
Web page be used for rt2870 or other wifi modules?    

Original comment by dreamdou...@126.com on 22 May 2010 at 9:01

GoogleCodeExporter commented 9 years ago
[deleted comment]
GoogleCodeExporter commented 9 years ago
I'm using the same chip (rt2870) and linuxconsultings custom wext-driver 
(awext), it 
works but it is not stable.
Awext should as far as i understand work for most wifi-drivers.

Original comment by rough...@gmail.com on 24 May 2010 at 12:17

GoogleCodeExporter commented 9 years ago
Thanks,roughack.I will try it on My Beagleboard

Original comment by dreamdou...@126.com on 24 May 2010 at 3:35

GoogleCodeExporter commented 9 years ago
Hi,roughack.I follow the web page to port rt2870 to Rowboat Androd(eclair) on 
beagleboard,but not succeed.I check it for a long time but can not find the 
reason.The following is the logcat.

<-- RTMPAllocAdapterBlock, Status=0
ra0 (usb): not using net_device_ops yet
usbcore: registered new interface driver rt2870
E/wpa_supplicant(  872): Unsupported driver 'awext'.
D/WifiService(  734): ACTION_BATTERY_CHANGED pluggedType: 1
E/WifiHW  (  734): Supplicant not running, cannot connect
D/SettingsWifiEnabler(  803): Received wifi state changed from Enabling to 
Enabled
I/power   (  734): *** set_screen_state 1
E/WifiHW  (  734): Supplicant not running, cannot connect
E/SettingsWifiLayer(  803): Unable to scan for networks
I/NotificationService(  734): enqueueToast pkg=com.android.settings 
callback=android.app.ITransientNotification$Stub$Proxy@43efac20 duration=1
I/power   (  734): *** set_screen_state 1
I/power   (  734): *** set_screen_state 1
E/WifiHW  (  734): Supplicant not running, cannot connect
E/SettingsWifiLayer(  803): Unable to scan for networks
I/NotificationService(  734): enqueueToast pkg=com.android.settings 
callback=android.app.ITransientNotification$Stub$Proxy@43ea5d68 duration=1
I/power   (  734): *** set_screen_state 1
E/WifiHW  (  734): Supplicant not running, cannot connect
V/WifiStateTracker(  734): Supplicant died unexpectedly
I/power   (  734): *** set_screen_state 1
D/NetworkStateTracker(  734): setDetailed state, old =IDLE and new 
state=DISCONNECTED
D/ConnectivityService(  734): ConnectivityChange for WIFI: 
DISCONNECTED/DISCONNECTED
W/MobileDataStateTracker(  734): Ignoring feature request because could not 
acquire 
PhoneService
W/MobileDataStateTracker(  734): Could not enable APN type "default"
D/SettingsWifiEnabler(  803): Received wifi state changed from Enabled to 
Disabling
usbcore: deregistering interface driver rt2870
rtusb_disconnect: unregister usbnet usb-musb_hdrc-1.1
rtusb_disconnect: unregister_netdev(), dev->name=ra0!
 RTUSB disconnect successfully
<--- rtusb exit
D/SettingsWifiEnabler(  803): Received wifi state changed from Disabling to 
Disabled

   I have used the patch the web page gave,but the logcat say "Unsupported 
driver 'awext'",I don't know why.

Original comment by dreamdou...@126.com on 26 May 2010 at 3:16

GoogleCodeExporter commented 9 years ago
Hi,roughack,Now I can scan AP and connect to AP,but I can not get IP address 
useing 
dhcp.I don't know why.The following is the logcat.Have you ever faced such 
problem?

rt2870sta: module is from the staging directory, the quality is unknown, you 
have 
been warned.
rtusb init --->

=== pAd = c8922000, size = 558152 ===

<-- RTMPAllocAdapterBlock, Status=0
ra0 (usb): not using net_device_ops yet
usbcore: registered new interface driver rt2870
D/WifiService(  730): ACTION_BATTERY_CHANGED pluggedType: 1
E/WifiHW  (  730): Unable to open connection to supplicant 
on "/data/system/wpa_supplicant/ra0": Connection refused
<-- RTMPAllocTxRxRingMemory, Status=0
-->RTUSBVenderReset
<--RTUSBVenderReset
--> Error 2 opening /etc/Wireless/RT2870STA/RT2870STA.dat
1. Phy Mode = 0
2. Phy Mode = 0
3. Phy Mode = 0
RTMPSetPhyMode: channel is out of range, use first channel=1 
MCS Set = 00 00 00 00 00
<==== RTMPInitialize, Status=0
0x1300 = 00073200
I/wpa_supplicant(  892): CTRL-EVENT-STATE-CHANGE id=-1 state=2
D/SettingsWifiEnabler(  808): Received wifi state changed from Enabling to 
Enabled
I/power   (  730): *** set_screen_state 1
W/ActivityManager(  730): Unable to start service Intent { 
act=android.bluetooth.IBluetoothHeadset }: not found
E/BluetoothHeadset(  730): Could not bind to Bluetooth Headset Service
V/WifiStateTracker(  730): Connection to supplicant established, state=SCANNING
D/NetworkStateTracker(  730): setDetailed state, old =DISCONNECTED and new 
state=SCANNING
D/ConnectivityService(  730): Dropping ConnectivityChange for WIFI: 
DISCONNECTED/SCANNING
===>rt_ioctl_giwscan. 2(2) BSS returned, data->length = 232
==>rt_ioctl_siwfreq::SIOCSIWFREQ[cmd=0x8b04] (Channel=6)
I/wpa_supplicant(  892): CTRL-EVENT-SCAN-RESULTS  Ready
I/wpa_supplicant(  892): Trying to associate with 00:0a:eb:d0:e8:52 
(SSID='TP-LINK' 
freq=2437 MHz)
I/wpa_supplicant(  892): CTRL-EVENT-STATE-CHANGE id=-1 state=3
DRS: unkown mode,default use 11N 1S AP 
DRS: unkown mode (SupRateLen=0, ExtRateLen=0, MCSSet[0]=0x0, MCSSet[1]=0x0)
I/wpa_supplicant(  892): CTRL-EVENT-STATE-CHANGE id=0 state=4
I/wpa_supplicant(  892): Associated with 00:0a:eb:d0:e8:52
I/wpa_supplicant(  892): CTRL-EVENT-STATE-CHANGE id=0 state=7
I/wpa_supplicant(  892): CTRL-EVENT-CONNECTED - Connection to 00:0a:eb:d0:e8:52 
completed (auth) [id=0 id_str=]
V/WifiMonitor(  730): Event [Trying to associate with 00:0a:eb:d0:e8:52 
(SSID='TP-
LINK' freq=2437 MHz)]
V/WifiMonitor(  730): Event [CTRL-EVENT-STATE-CHANGE id=-1 state=3]
V/WifiStateTracker(  730): Changing supplicant state: SCANNING ==> ASSOCIATING
D/NetworkStateTracker(  730): setDetailed state, old =SCANNING and new 
state=CONNECTING
D/ConnectivityService(  730): ConnectivityChange for WIFI: CONNECTING/CONNECTING
V/WifiMonitor(  730): Event [CTRL-EVENT-STATE-CHANGE id=0 state=4]
V/WifiMonitor(  730): Event [Associated with 00:0a:eb:d0:e8:52]
V/WifiMonitor(  730): Event [CTRL-EVENT-STATE-CHANGE id=0 state=7]
V/WifiStateTracker(  730): Changing supplicant state: ASSOCIATING ==> ASSOCIATED
D/NetworkStateTracker(  730): setDetailed state, old =CONNECTING and new 
state=CONNECTING
V/WifiStateTracker(  730): Changing supplicant state: ASSOCIATED ==> COMPLETED
V/WifiMonitor(  730): Event [CTRL-EVENT-CONNECTED - Connection to 
00:0a:eb:d0:e8:52 
completed (auth) [id=0 id_str=]]
V/WifiStateTracker(  730): New network state is CONNECTED
W/BluetoothHeadset(  730): Proxy not attached to service
D/WifiStateTracker(  730): DhcpHandler: DHCP request started
D/NetworkStateTracker(  730): setDetailed state, old =CONNECTING and new 
state=OBTAINING_IPADDR
D/ConnectivityService(  730): Dropping ConnectivityChange for WIFI: 
CONNECTING/OBTAINING_IPADDR
D/SettingsWifiEnabler(  808): Received network state changed to NetworkInfo: 
type: 
WIFI[], state: CONNECTING/OBTAINING_IPADDR, reason: (unspece
I/WifiStateTracker(  730): DhcpHandler: DHCP request failed: Timed out waiting 
for 
dhcpcd to start
I/wpa_supplicant(  892): CTRL-EVENT-STATE-CHANGE id=0 state=8
I/wpa_supplicant(  892): CTRL-EVENT-DISCONNECTED - Disconnect event - remove 
keys
I/wpa_supplicant(  892): CTRL-EVENT-STATE-CHANGE id=-1 state=8
V/WifiMonitor(  730): Event [CTRL-EVENT-STATE-CHANGE id=0 state=8]
V/WifiMonitor(  730): Event [CTRL-EVENT-DISCONNECTED - Disconnect event - 
remove 
keys]
V/WifiMonitor(  730): Event [CTRL-EVENT-STATE-CHANGE id=-1 state=8]
V/WifiStateTracker(  730): Changing supplicant state: COMPLETED ==> DORMANT
D/WifiStateTracker(  730): Deconfiguring interface and stopping DHCP
I/wpa_supplicant(  892): CTRL-EVENT-DISCONNECTED - Disconnect event - remove 
keys
I/wpa_supplicant(  892): CTRL-EVENT-STATE-CHANGE id=-1 state=8
V/WifiMonitor(  730): Event [CTRL-EVENT-DISCONNECTED - Disconnect event - 
remove 
keys]
V/WifiMonitor(  730): Event [CTRL-EVENT-STATE-CHANGE id=-1 state=8]
Terminate the TimerQThr_pid=897!
Terminate the MLMEThr_pid=895!
Terminate the RTUSBCmdThr_pid=896!
---> RTMPFreeTxRxRingMemory
<--- ReleaseAdapter
D/NetworkStateTracker(  730): setDetailed state, old =OBTAINING_IPADDR and new 
state=FAILED
D/SettingsWifiEnabler(  808): Received network state changed to NetworkInfo: 
type: 
WIFI[], state: DISCONNECTED/FAILED, reason: (unspecified), e
V/WifiStateTracker(  730): New network state is DISCONNECTED
V/WifiStateTracker(  730): Changing supplicant state: DORMANT ==> DORMANT
V/WifiStateTracker(  730): New network state is DISCONNECTED
V/WifiStateTracker(  730): Changing supplicant state: DORMANT ==> DORMANT
D/ConnectivityService(  730): ConnectivityChange for WIFI: DISCONNECTED/FAILED
V/ConnectivityService(  730): Attempt to connect to WIFI failed.
W/MobileDataStateTracker(  730): Ignoring feature request because could not 
acquire 
PhoneService
W/MobileDataStateTracker(  730): Could not enable APN type "default"
I/wpa_supplicant(  892): CTRL-EVENT-STATE-CHANGE id=-1 state=2
V/WifiMonitor(  730): Event [CTRL-EVENT-STATE-CHANGE id=-1 state=2]
V/WifiStateTracker(  730): Changing supplicant state: DORMANT ==> SCANNING
D/NetworkStateTracker(  730): setDetailed state, old =FAILED and new 
state=SCANNING
D/ConnectivityService(  730): Dropping ConnectivityChange for WIFI: 
DISCONNECTED/SCANNING
D/dalvikvm(  833): GC freed 3184 objects / 197816 bytes in 61ms
I/power   (  730): *** set_screen_state 1

Original comment by dreamdou...@126.com on 28 May 2010 at 10:26

GoogleCodeExporter commented 9 years ago
The awext thing is because some defines is missing in some wpa-files where they 
define up a list of supported drivers.

The dhcp thing is probably a folder issue.
Check if /data/misc/dhcp exists and that dhcpcd can write in it, I just chmoded 
it to 
777, had to create it thou.

If it doesn't work try starting dhcpcd with the command "dhcpcd ra0" and look 
at what 
it says. You might have to do it while Android tries to connect to your AP so 
that 
wpa_supplicant have associated the wifi driver to the right ssid.
It could also be that you forgot to add "service dhcpcd...." in your init.rc 
and then 
wpa cant find it.
I can tell you that after you get dhcpcd to work it should work, but don't 
expect it 
to work flawlessly, might need to upgrade to a 2.6.32 kernel and change to the 
rt2x00 
driver from serialmonkey for that. I haven't done that myself because I have 
some 
more important devices that gives me more headaches in the .32 kernel than it 
does in  
the .29.

Original comment by rough...@gmail.com on 31 May 2010 at 2:29

GoogleCodeExporter commented 9 years ago
I saw another thing in your log.

--> Error 2 opening /etc/Wireless/RT2870STA/RT2870STA.dat
The driver wants that file, if you compiled the driver from ralinktech 
(2.3.0.0) you 
should have it somewhere in there. don't even think you need to compile the 
source to 
get it.
If it is already there make sure the driver can read it.

Original comment by rough...@gmail.com on 31 May 2010 at 2:35

GoogleCodeExporter commented 9 years ago
[deleted comment]
GoogleCodeExporter commented 9 years ago
[deleted comment]
GoogleCodeExporter commented 9 years ago
I have fixed the problems.Thanks for you help.You suggestion is very helpful 
for me

Original comment by dreamdou...@126.com on 31 May 2010 at 12:41

GoogleCodeExporter commented 9 years ago

D/WifiService(  915): ACTION_BATTERY_CHANGED pluggedType: 1
I//system/bin/tc(  870): Cannot find device "rmnet0"
I/logwrapper(  870): /system/bin/tc terminated by exit(1)
D/wpa_supplicant( 1025): Initializing interface 'mlan0' conf 
'/data/misc/wifi/wpa_supplicant.conf' driver 'awext' ctrl_interface 'N/A' 
bridge 'N/A'
D/wpa_supplicant( 1025): Configuration file 
'/data/misc/wifi/wpa_supplicant.conf' -> '/data/misc/wifi/wpa_supplicant.conf'
D/wpa_supplicant( 1025): Reading configuration file 
'/data/misc/wifi/wpa_supplicant.conf'
E/WifiHW  (  915): Unable to open connection to supplicant on 
"/data/system/wpa_supplicant/mlan0": No such file or directory
I//system/bin/tc(  870): Cannot find device "rmnet0"
I/logwrapper(  870): /system/bin/tc terminated by exit(1)
D/wpa_supplicant( 1025): update_config=1
D/wpa_supplicant( 1025): ctrl_interface='mlan0'
D/wpa_supplicant( 1025): ctrl_interface_group='wifi' (DEPRECATED)
D/wpa_supplicant( 1025): Priority group 0
D/wpa_supplicant( 1025):    id=0 ssid=''
E/wpa_supplicant( 1025): 
E/wpa_supplicant( 1025): wpa_supplicant_init_iface finish.
D/wpa_supplicant( 1025): Initializing interface (2) 'mlan0'
D/wpa_supplicant( 1025): EAPOL: SUPP_PAE entering state DISCONNECTED
D/wpa_supplicant( 1025): EAPOL: KEY_RX entering state NO_KEY_RECEIVE
D/wpa_supplicant( 1025): EAPOL: SUPP_BE entering state INITIALIZE
D/wpa_supplicant( 1025): EAP: EAP entering state DISABLED
D/wpa_supplicant( 1025): EAPOL: External notification - portEnabled=0
D/wpa_supplicant( 1025): EAPOL: External notification - portValid=0
I/ActivityThread(  995): Publishing provider com.android.launcher2.settings: 
com.android.launcher2.LauncherProvider
D/dalvikvm(  995): GC_EXTERNAL_ALLOC freed 879 objects / 64912 bytes in 505ms
I/ActivityThread(  991): Publishing provider mms: 
com.android.providers.telephony.MmsProvider
D/dalvikvm(  915): GC_EXTERNAL_ALLOC freed 3591 objects / 230040 bytes in 268ms
I/ActivityThread(  991): Publishing provider sms: 
com.android.providers.telephony.SmsProvider
I/ActivityThread(  991): Publishing provider telephony: 
com.android.providers.telephony.TelephonyProvider
I/ActivityThread(  991): Publishing provider icc: com.android.phone.IccProvider
I/ActivityThread(  991): Publishing provider mms-sms: 
com.android.providers.telephony.MmsSmsProvider
D/ConnectivityService(  915): getMobileDataEnabled returning true
I//system/bin/tc(  870): Cannot find device "ifb0"
I/logwrapper(  870): /system/bin/tc terminated by exit(1)
I/ActivityManager(  915): Start proc android.process.acore for content provider 
com.android.providers.contacts/.CallLogProvider: pid=1050 uid=10005 gids={3003, 
1015}
D/wpa_supplicant( 1025): SIOCGIWRANGE: WE(compiled)=22 WE(source)=14 
enc_capa=0xf
D/wpa_supplicant( 1025):   capabilities: key_mgmt 0xf enc 0xf
D/wpa_supplicant( 1025): WEXT: Operstate: linkmode=1, operstate=5
D/wpa_supplicant( 1025): Own MAC address: 00:19:94:10:d7:d0
D/wpa_supplicant( 1025): wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 
seq_len=0 key_len=0
D/wpa_supplicant( 1025): wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 
seq_len=0 key_len=0
D/wpa_supplicant( 1025): wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 
seq_len=0 key_len=0
D/wpa_supplicant( 1025): wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 
seq_len=0 key_len=0
D/wpa_supplicant( 1025): wpa_driver_awext_set_countermeasures
D/wpa_supplicant( 1025): wpa_driver_awext_set_drop_unencrypted
D/wpa_supplicant( 1025): Setting scan request: 0 sec 100000 usec
D/wpa_supplicant( 1025): ctrl_interface_group=1010 (from group name 'wifi')
D/dalvikvm(  915): GREF has increased to 201
I/ActivityThread( 1050): Publishing provider com.android.social: 
com.android.providers.contacts.SocialProvider
D/wpa_supplicant( 1025): Added interface mlan0
D/wpa_supplicant( 1025): RTM_NEWLINK: operstate=0 ifi_flags=0x11043 
([UP][RUNNING][LOWER_UP])
D/wpa_supplicant( 1025): RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
D/wpa_supplicant( 1025): RTM_NEWLINK: operstate=0 ifi_flags=0x11043 
([UP][RUNNING][LOWER_UP])
D/wpa_supplicant( 1025): RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
D/wpa_supplicant( 1025): Wireless event: cmd=0x8b06 len=8
D/wpa_supplicant( 1025): RTM_NEWLINK: operstate=0 ifi_flags=0x11003 
([UP][LOWER_UP])
D/wpa_supplicant( 1025): RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
D/wpa_supplicant( 1025): sssid====>
D/wpa_supplicant( 1025): State: DISCONNECTED -> SCANNING
I/wpa_supplicant( 1025): CTRL-EVENT-STATE-CHANGE id=-1 state=2
D/wpa_supplicant( 1025): Starting AP scan (broadcast SSID)
D/wpa_supplicant( 1025): Trying to get current scan results first without 
requesting a new scan to speed up initial association
D/wpa_supplicant( 1025): Scan results: -1
D/wpa_supplicant( 1025): Failed to get scan results
D/wpa_supplicant( 1025): Failed to get scan results - try scanning again
D/wpa_supplicant( 1025): Setting scan request: 0 sec 0 usec
D/wpa_supplicant( 1025): sssid====>
D/wpa_supplicant( 1025): Starting AP scan (broadcast SSID)
D/wpa_supplicant( 1025): Scan requested (ret=0) - scan timeout 5 seconds
I/ActivityThread( 1050): Publishing provider applications: 
com.android.providers.applications.ApplicationsProvider
D/PhoneApp(  991): onCreate: mProximityWakeLock: null
W/ActivityManager(  915): Unable to start service Intent { 
act=com.android.ussd.IExtendedNetworkService }: not found
I/ActivityThread( 1050): Publishing provider contacts;com.android.contacts: 
com.android.providers.contacts.ContactsProvider2
D/PhoneApp(  991): Resetting audio state/mode: IDLE
D/MobileDataStateTracker(  915): default Received state= DISCONNECTED, old= 
DISCONNECTED, reason= radioTurnedOff, apnTypeList= default
D/MobileDataStateTracker(  915): default Received state= DISCONNECTED, old= 
DISCONNECTED, reason= gprsDetached, apnTypeList= default
D/BT HSHFP(  991): Starting BluetoothHeadsetService
I/SurfaceFlinger(  915): Boot is finished (23886 ms)
D/PowerManagerService(  915): bootCompleted
I/ARMAssembler(  915): generated scanline__00000177:03515104_00000001_00000000 
[ 73 ipp] (95 ins) at [0x2da690:0x2da80c] in 335693 ns
E/WifiHW  (  915): Unable to open connection to supplicant on 
"/data/system/wpa_supplicant/mlan0": No such file or directory
D/OtaStartupReceiver(  991): Not a CDMA phone, no need to process OTA
I/ActivityManager(  915): Start proc android.process.media for broadcast 
com.android.providers.downloads/.DownloadReceiver: pid=1066 uid=10006 
gids={1015, 1006, 2001, 3003}
I/RecoverySystem(  915): No recovery log file
I/ActivityThread( 1050): Publishing provider call_log: 
com.android.providers.contacts.CallLogProvider
I/ActivityThread( 1066): Publishing provider media: 
com.android.providers.media.MediaProvider
I/ActivityThread( 1050): Publishing provider user_dictionary: 
com.android.providers.userdictionary.UserDictionaryProvider
V/MediaProvider( 1066): Attached volume: internal
I/ActivityThread( 1066): Publishing provider downloads: 
com.android.providers.downloads.DownloadProvider
I/SearchManagerService(  915): Building list of searchable activities
D/dalvikvm(  915): GC_FOR_MALLOC freed 7596 objects / 355504 bytes in 139ms
I/ActivityManager(  915): Start proc com.qo.android.moto for broadcast 
com.qo.android.moto/com.qo.android.updates.MyStartupIntentReceiver: pid=1078 
uid=10035 gids={3003, 1015}
D/dalvikvm(  873): GC_EXPLICIT freed 275 objects / 10384 bytes in 84ms
D/dalvikvm(  873): GC_EXPLICIT freed 45 objects / 1992 bytes in 164ms
D/dalvikvm(  873): GC_EXPLICIT freed 2 objects / 48 bytes in 142ms
D/MediaScannerService( 1066): start scanning volume internal
I/ActivityManager(  915): Start proc com.esmertec.android.jbed for broadcast 
com.esmertec.android.jbed/.BootCompleteReceiver: pid=1087 uid=10038 gids={3003, 
1006}
I/ActivityThread( 1087): Publishing provider jbed: 
com.esmertec.android.jbed.JbedProvider
I/BootCompleteReceiver( 1087): onReceive receive action 
android.intent.action.BOOT_COMPLETED
I/BootCompleteReceiver( 1087): start JbedVmNoRunningService
I/ActivityManager(  915): Start proc com.android.quicksearchbox for broadcast 
com.android.quicksearchbox/.SearchWidgetProvider: pid=1097 uid=10011 gids={3003}
D/wpa_supplicant( 1025): Scan timeout - try to get results
D/wpa_supplicant( 1025): Received 989 bytes of scan results (9 BSSes)
D/wpa_supplicant( 1025): Scan results: 9
I/wpa_supplicant( 1025): CTRL-EVENT-SCAN-RESULTS  Ready
D/wpa_supplicant( 1025): Selecting BSS from priority group 0
D/wpa_supplicant( 1025): Try to find WPA-enabled AP
D/wpa_supplicant( 1025): 0: 00:1b:11:fc:bc:a2 ssid='inno12N' wpa_ie_len=24 
rsn_ie_len=0 caps=0x11
D/wpa_supplicant( 1025):    skip - SSID mismatch
D/wpa_supplicant( 1025): 1: 02:23:76:a1:13:ce ssid='orion' wpa_ie_len=0 
rsn_ie_len=22 caps=0x11
D/wpa_supplicant( 1025):    skip - SSID mismatch
D/wpa_supplicant( 1025): 2: 42:90:a3:c4:7e:3b ssid='mmHome' wpa_ie_len=0 
rsn_ie_len=0 caps=0x12
D/wpa_supplicant( 1025):    skip - no WPA/RSN IE
D/wpa_supplicant( 1025): 3: 00:0b:0e:9c:ae:40 ssid='sunwifi' wpa_ie_len=0 
rsn_ie_len=0 caps=0x1
D/wpa_supplicant( 1025):    skip - no WPA/RSN IE
D/wpa_supplicant( 1025): 4: 02:1f:3b:01:7a:3d ssid='innofidei802' wpa_ie_len=0 
rsn_ie_len=0 caps=0x2
D/wpa_supplicant( 1025):    skip - no WPA/RSN IE
D/wpa_supplicant( 1025): 5: 00:0b:0e:9c:b0:80 ssid='sunwifi' wpa_ie_len=0 
rsn_ie_len=0 caps=0x1
D/wpa_supplicant( 1025):    skip - no WPA/RSN IE
D/wpa_supplicant( 1025): 6: 00:0b:0e:9c:dc:40 ssid='sunwifi' wpa_ie_len=0 
rsn_ie_len=0 caps=0x1
D/wpa_supplicant( 1025):    skip - no WPA/RSN IE
D/wpa_supplicant( 1025): 7: 00:0b:0e:9c:9f:80 ssid='sunwifi' wpa_ie_len=0 
rsn_ie_len=0 caps=0x1
D/wpa_supplicant( 1025):    skip - no WPA/RSN IE
D/wpa_supplicant( 1025): 8: 00:22:b0:cb:f0:37 ssid='dlink' wpa_ie_len=0 
rsn_ie_len=0 caps=0x1
D/wpa_supplicant( 1025):    skip - no WPA/RSN IE
D/wpa_supplicant( 1025): Try to find non-WPA AP
D/wpa_supplicant( 1025): 0: 00:1b:11:fc:bc:a2 ssid='inno12N' wpa_ie_len=24 
rsn_ie_len=0 caps=0x11
D/wpa_supplicant( 1025):    skip - privacy mismatch
D/wpa_supplicant( 1025): 1: 02:23:76:a1:13:ce ssid='orion' wpa_ie_len=0 
rsn_ie_len=22 caps=0x11
D/wpa_supplicant( 1025):    skip - privacy mismatch
D/wpa_supplicant( 1025): 2: 42:90:a3:c4:7e:3b ssid='mmHome' wpa_ie_len=0 
rsn_ie_len=0 caps=0x12
D/wpa_supplicant( 1025):    skip - privacy mismatch
D/wpa_supplicant( 1025): 3: 00:0b:0e:9c:ae:40 ssid='sunwifi' wpa_ie_len=0 
rsn_ie_len=0 caps=0x1
D/wpa_supplicant( 1025):    selected non-WPA AP 00:0b:0e:9c:ae:40 ssid='sunwifi'
I/wpa_supplicant( 1025): Trying to associate with 00:0b:0e:9c:ae:40 
(SSID='sunwifi' freq=2412 MHz)
D/wpa_supplicant( 1025): Cancelling scan request
D/wpa_supplicant( 1025): WPA: clearing own WPA/RSN IE
D/wpa_supplicant( 1025): Automatic auth_alg selection: 0x1
D/wpa_supplicant( 1025): WPA: clearing AP WPA IE
D/wpa_supplicant( 1025): WPA: clearing AP RSN IE
D/wpa_supplicant( 1025): WPA: clearing own WPA/RSN IE
D/wpa_supplicant( 1025): No keys have been configured - skip key clearing
D/wpa_supplicant( 1025): wpa_driver_awext_set_drop_unencrypted
D/wpa_supplicant( 1025): State: SCANNING -> ASSOCIATING
I/wpa_supplicant( 1025): CTRL-EVENT-STATE-CHANGE id=-1 state=3
D/wpa_supplicant( 1025): wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
D/wpa_supplicant( 1025): WEXT: Operstate: linkmode=-1, operstate=5
D/wpa_supplicant( 1025): wpa_driver_awext_associate
D/wpa_supplicant( 1025): Setting authentication timeout: 10 sec 0 usec
D/wpa_supplicant( 1025): EAPOL: External notification - 
portControl=ForceAuthorized
D/wpa_supplicant( 1025): RTM_NEWLINK: operstate=0 ifi_flags=0x11003 
([UP][LOWER_UP])
D/wpa_supplicant( 1025): RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
D/wpa_supplicant( 1025): Wireless event: cmd=0x8b06 len=8
D/wpa_supplicant( 1025): RTM_NEWLINK: operstate=0 ifi_flags=0x11003 
([UP][LOWER_UP])
D/wpa_supplicant( 1025): RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
D/wpa_supplicant( 1025): Wireless event: cmd=0x8b04 len=12
D/wpa_supplicant( 1025): RTM_NEWLINK: operstate=0 ifi_flags=0x11003 
([UP][LOWER_UP])
D/wpa_supplicant( 1025): RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
D/wpa_supplicant( 1025): Wireless event: cmd=0x8c07 len=8
D/wpa_supplicant( 1025): RTM_NEWLINK: operstate=0 ifi_flags=0x11003 
([UP][LOWER_UP])
D/wpa_supplicant( 1025): RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
D/wpa_supplicant( 1025): Wireless event: cmd=0x8b15 len=20
D/wpa_supplicant( 1025): Wireless event: new AP: 00:0b:0e:9c:ae:40
D/wpa_supplicant( 1025): Association info event
D/wpa_supplicant( 1025): WPA: clearing own WPA/RSN IE
D/wpa_supplicant( 1025): WPA: clearing AP WPA IE
D/wpa_supplicant( 1025): WPA: clearing AP RSN IE
D/wpa_supplicant( 1025): State: ASSOCIATING -> ASSOCIATED
I/wpa_supplicant( 1025): CTRL-EVENT-STATE-CHANGE id=0 state=4
D/wpa_supplicant( 1025): wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
D/wpa_supplicant( 1025): WEXT: Operstate: linkmode=-1, operstate=5
D/wpa_supplicant( 1025): Associated to a new BSS: BSSID=00:0b:0e:9c:ae:40
I/wpa_supplicant( 1025): Associated with 00:0b:0e:9c:ae:40
D/wpa_supplicant( 1025): WPA: Association event - clear replay counter
D/wpa_supplicant( 1025): EAPOL: External notification - portEnabled=0
D/wpa_supplicant( 1025): EAPOL: External notification - portValid=0
D/wpa_supplicant( 1025): EAPOL: External notification - portEnabled=1
D/wpa_supplicant( 1025): EAPOL: SUPP_PAE entering state S_FORCE_AUTH
D/wpa_supplicant( 1025): EAPOL: SUPP_BE entering state IDLE
D/wpa_supplicant( 1025): Cancelling authentication timeout
D/wpa_supplicant( 1025): State: ASSOCIATED -> COMPLETED
I/wpa_supplicant( 1025): CTRL-EVENT-STATE-CHANGE id=0 state=7
I/wpa_supplicant( 1025): CTRL-EVENT-CONNECTED - Connection to 00:0b:0e:9c:ae:40 
completed (auth) [id=0 id_str=]
D/wpa_supplicant( 1025): wpa_driver_wext_set_operstate: operstate 0->1 (UP)
D/wpa_supplicant( 1025): WEXT: Operstate: linkmode=-1, operstate=6
D/wpa_supplicant( 1025): Cancelling scan request
D/wpa_supplicant( 1025): RTM_NEWLINK: operstate=1 ifi_flags=0x11043 
([UP][RUNNING][LOWER_UP])
D/wpa_supplicant( 1025): RTM_NEWLINK, IFLA_IFNAME: Interface 'mlan0' added
I/ActivityThread( 1097): Publishing provider com.android.quicksearchbox.google: 
com.android.quicksearchbox.google.GoogleSuggestionProvider
W/JbedConfig( 1087): WARNING:getString, property:LAUNCH.POWER.ON is null
D/        ( 1066): opendir /system/media/ failed, errno: 2
D/MediaScanner( 1066):  prescan time: 1810ms
D/MediaScanner( 1066):     scan time: 1ms
D/MediaScanner( 1066): postscan time: 0ms
D/MediaScanner( 1066):    total time: 1811ms
D/MediaScannerService( 1066): done scanning volume internal
E/WifiHW  (  915): Unable to open connection to supplicant on 
"/data/system/wpa_supplicant/mlan0": No such file or directory
D/dalvikvm(  995): GC_EXPLICIT freed 7151 objects / 370760 bytes in 53ms
E/WifiHW  (  915): Unable to open connection to supplicant on 
"/data/system/wpa_supplicant/mlan0": No such file or directory
V/WifiStateTracker(  915): Supplicant died unexpectedly
D/NetworkStateTracker(  915): setDetailed state, old =IDLE and new 
state=DISCONNECTED
D/ConnectivityService(  915): ConnectivityChange for WIFI: 
DISCONNECTED/DISCONNECTED
D/ConnectivityService(  915): getMobileDataEnabled returning true
I/wpa_supplicant( 1025): CTRL-EVENT-TERMINATING - signal 15 received
I/wpa_supplicant( 1025): CTRL-EVENT-TERMINATING - signal 0 received
D/wpa_supplicant( 1025): Removing interface mlan0
D/wpa_supplicant( 1025): State: COMPLETED -> DISCONNECTED
I/wpa_supplicant( 1025): CTRL-EVENT-STATE-CHANGE id=0 state=0
D/wpa_supplicant( 1025): wpa_driver_wext_set_operstate: operstate 1->0 (DORMANT)
D/wpa_supplicant( 1025): WEXT: Operstate: linkmode=-1, operstate=5
D/wpa_supplicant( 1025): wpa_driver_awext_deauthenticate
D/DataConnectionTracker(  991): enableApnType(default), isApnTypeActive = false 
and state = IDLE
D/DataConnectionTracker(  991): type not available
D/wpa_supplicant( 1025): No keys have been configured - skip key clearing
D/wpa_supplicant( 1025): EAPOL: External notification - portEnabled=0
D/wpa_supplicant( 1025): EAPOL: SUPP_PAE entering state DISCONNECTED
D/wpa_supplicant( 1025): EAPOL: SUPP_BE entering state INITIALIZE
D/wpa_supplicant( 1025): EAPOL: External notification - portValid=0
D/wpa_supplicant( 1025): wpa_driver_awext_set_drop_unencrypted
D/wpa_supplicant( 1025): wpa_driver_awext_set_countermeasures
D/wpa_supplicant( 1025): No keys have been configured - skip key clearing
D/Tethering(  915): Tethering got CONNECTIVITY_ACTION
D/Tethering(  915): MasterInitialState.processMessage what=3
E/HierarchicalStateMachine(  915): TetherMaster - unhandledMessage: msg.what=3
D/wpa_supplicant( 1025): Cancelling scan request
D/wpa_supplicant( 1025): Cancelling authentication timeout
D/wpa_supplicant( 1025): WEXT: Operstate: linkmode=0, operstate=6
D/WifiStateTracker(  915): Reset connections and stopping DHCP
D/WifiStateTracker(  915): Disabling interface
D/WifiHW  (  915): Unable to unload driver module "mlan0": No such file or 
directory
E/WifiService(  915): Failed to unload Wi-Fi driver.
I/dalvikvm(  915): Jit: resizing JitTable from 4096 to 8192
I/power   (  915): *** set_screen_state 0
E/power   (  915): Failed setting last user activity: g_error=0

I usually got this error

===>rt_ioctl_giwscan. WPA_SUPPLICANT_ENABLE in
===>rt_ioctl_giwscan. 9(9) BSS returned, data->length = 989
==>rt_ioctl_siwfreq::SIOCSIWFREQ[cmd=0x8b04] (Channel=1)
Terminate the task(RtmpMlmeTask) with pid(1038)!
Terminate the task(RtmpCmdQTask) with pid(1039)!
Terminate the task(RtmpTimerTask) with pid(1037)!
---> RTMPFreeTxRxRingMemory
<--- RTMPFreeTxRxRingMemory

who can help me why this happen? It seems that the wpa_supplicant hung up.

Original comment by chinaren...@gmail.com on 14 Jul 2010 at 10:47