Linux/WL1831MOD: Unable to add wlan0 into bridge mode

HI Yordan,
Thanks for your reply.

i have tried the same step like this:
1. This is our WIFI_STA_ON.sh script:

ifconfig wlan0 up
sleep 1
wpa_supplicant -d -Dnl80211 -c/etc/wpa_supplicant.conf -iwlan0 -B
sleep 1
wpa_cli -iwlan0 disconnect
for i in `wpa_cli -iwlan0 list_networks | grep ^[0-9] | cut -f1`; do wpa_cli -iwlan0 remove_network $i; done
wpa_cli -iwlan0 add_network
wpa_cli -iwlan0 set_network 0 auth_alg OPEN
wpa_cli -iwlan0 set_network 0 key_mgmt WPA-PSK
wpa_cli -iwlan0 set_network 0 psk '"micromax"'
wpa_cli -iwlan0 set_network 0 pairwise CCMP TKIP
wpa_cli -iwlan0 set_network 0 group CCMP TKIP
wpa_cli -iwlan0 set_network 0 mode 0
wpa_cli -iwlan0 set_network 0 ssid '"AT&T"'
wpa_cli -iwlan0 select_network 0
wpa_cli -iwlan0 enable_network 0
wpa_cli -iwlan0 reassociate
wpa_cli -iwlan0 status
udhcpc -i wlan0
echo 1 > /sys/class/gpio/gpio17/value
echo 0 > /sys/class/gpio/gpio18/value

2. This is our Script_bridge.sh:
lsmod
sleep 1
iw wlan0 set 4addr on
sleep 2
iw $(ls /sys/class/ieee80211/) interface add wlan1 type managed
sleep 2
iw wlan1 set 4addr on
sleep 3
ifconfig wlan0 up
sleep 2
ifconfig wlan1 up
sleep 2
brctl addbr br0
ifconfig br0 192.168.44.1 netmask 255.255.255.0 up
sleep 2
brctl addif br0 wlan1
sleep 2
brctl addif br0 wlan0
sleep 30
sh /home/root/system_init/WIFI_STA_ON.sh


Logs:
root@imx6slzbha:~# sh Script_bridge.sh
Tainted: G
wl18xx 72328 0 - Live 0x7f0e5000 (O)
wlcore_sdio 4145 0 - Live 0x7f0e0000 (O)
wlcore 156846 1 wl18xx, Live 0x7f0ab000 (O)
mac80211 349608 2 wl18xx,wlcore, Live 0x7f03b000 (O)
cfg80211 162831 3 wl18xx,wlcore,mac80211, Live 0x7f004000 (O)
compat 1860 4 wl18xx,wlcore_sdio,mac80211,cfg80211, Live 0x7f000000 (O)
wlcore: PHY firmware version: Rev 8.2.0.0.195
wlcore: firmware booted (Rev 8.8.0.0.13)
IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
device wlan1 entered promiscuous mode
device wlan0 entered promiscuous mode
wpa_supplicant v2.1
random: Trying to read entropy from /dev/random
Successfully initialized wpa_supplicant
Initializing interface 'wlan0' conf '/etc/wpa_supplicant.conf' driver 'nl80211' ctrl_interface 'N/A' bridge 'N/A'
Configuration file '/etc/wpa_supplicant.conf' -> '/etc/wpa_supplicant.conf'
Reading configuration file '/etc/wpa_supplicant.conf'
ctrl_interface='/var/run/wpa_supplicant'
ctrl_interface_group='0'
update_config=1
Priority group 0
id=0 ssid=''
nl80211: Could not add multicast membership for vendor events: -2 (No such file or directory)
rfkill: initial event: idx=0 type=2 op=0 soft=0 hard=0
rfkill: initial event: idx=1 type=1 op=0 soft=0 hard=0
nl80211: Supported cipher 00-0f-ac:1
nl80211: Supported cipher 00-0f-ac:5
nl80211: Supported cipher 00-0f-ac:2
nl80211: Supported cipher 00-0f-ac:4
nl80211: Supported cipher 00-14-72:1
nl80211: Supports Probe Response offload in AP mode
nl80211: Using driver-based off-channel TX
nl80211: Use separate P2P group interface (driver advertised support)
nl80211: Enable multi-channel concurrent (driver advertised support)
nl80211: interface wlan0 in phy phy0
nl80211: Set mode ifindex 4 iftype 2 (STATION)
nl80211: Subscribe to mgmt frames with non-AP handle 0x2573a0
nl80211: Register frame type=0xd0 nl_handle=0x2573a0 match=06
nl80211: Register frame type=0xd0 nl_handle=0x2573a0 match=0a07
nl80211: Register frame type=0xd0 nl_handle=0x2573a0 match=0a11
netlink: Operstate: ifindex=4 linkmode=1 (userspace-control), operstate=5 (IF_OPER_DORMANT)
nl80211: driver param='(null)'
Add interface wlan0 to a new radio phy0
nl80211: Regulatory information - country=00
nl80211: 2402-2472 @ 40 MHz 20 mBm
nl80211: 2457-2482 @ 40 MHz 20 mBm
nl80211: 2474-2494 @ 20 MHz 20 mBm
nl80211: 5170-5250 @ 160 MHz 20 mBm
nl80211: 5250-5330 @ 160 MHz 20 mBm
nl80211: 5490-5730 @ 160 MHz 20 mBm
nl80211: Added 802.11b mode based on 802.11g information
wlan0: Own MAC address: 78:a5:04:26:72:44
wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=(nil) key_idx=0 set_tx=0 seq_len=0 key_len=0
wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=(nil) key_idx=1 set_tx=0 seq_len=0 key_len=0
wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=(nil) key_idx=2 set_tx=0 seq_len=0 key_len=0
wpa_driver_nl80211_set_key: ifindex=4 (wlan0) alg=0 addr=(nil) key_idx=3 set_tx=0 seq_len=0 key_len=0
wlan0: RSN: flushing PMKID list in the driver
nl80211: Flush PMKIDs
wlan0: WPS: UUID based on MAC address: 116c5382-4500-506d-8fc8-202bbd61bdb4
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: Supplicant port status: Unauthorized
nl80211: Skip set_supp_port(unauthorized) while not associated
EAPOL: KEY_RX entering state NO_KEY_RECEIVE
EAPOL: SUPP_BE entering state INITIALIZE
EAP: EAP entering state DISABLED
Using existing control interface directory.
ctrl_interface_group=0
wlan0: Added interface wlan0
wlan0: State: DISCONNECTED -> DISCONNECTED
nl80211: Set wlan0 operstate 0->0 (DORMANT)
netlink: Operstate: ifindex=4 linkmode=-1 (no change), operstate=5 (IF_OPER_DORMANT)
Daemonize..
wlan0: authenticate with 40:e3:d6:a2:94:08
wlan0: send auth to 40:e3:d6:a2:94:08 (try 1/3)
wlan0: authenticated
wlan0: associate with 40:e3:d6:a2:94:08 (try 1/3)
wlan0: RX AssocResp from 40:e3:d6:a2:94:08 (capab=0x421 status=0 aid=1)
IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
wlan0: associated
wlcore: Association completed.
br0: port 2(wlan0) entered forwarding state
br0: port 2(wlan0) entered forwarding state
wlcore: ERROR SW watchdog interrupt received! starting recovery.
------------[ cut here ]------------
WARNING: at /home/sandeep/Desktop/SDK/stct_iog1/source/yocto/build/tmp/work/imx6slzbha-poky-linux-gnueabi/ti-compat-wl18xx/r8.a8.10-r0/compat_wireless/drivers/net/wirel)

Modules linked in: wl18xx(O) wlcore_sdio(O) wlcore(O) mac80211(O) cfg80211(O) compat(O)
CPU: 0 PID: 323 Comm: irq/283-wl18xx Tainted: G O 3.10.17_BETA-2015-02-12-00_21-1.0.0_ga #13
[<800138c0>] (unwind_backtrace+0x0/0xf4) from [<800114f8>] (show_stack+0x10/0x14)
[<800114f8>] (show_stack+0x10/0x14) from [<80025e20>] (warn_slowpath_common+0x54/0x6c)
[<80025e20>] (warn_slowpath_common+0x54/0x6c) from [<80025ed4>] (warn_slowpath_null+0x1c/0x24)
[<80025ed4>] (warn_slowpath_null+0x1c/0x24) from [<7f0b02cc>] (wl12xx_queue_recovery_work.part.37+0x58/0x5c [wlcore])
[<7f0b02cc>] (wl12xx_queue_recovery_work.part.37+0x58/0x5c [wlcore]) from [<7f0b0548>] (wlcore_irq+0x158/0x16c [wlcore])
[<7f0b0548>] (wlcore_irq+0x158/0x16c [wlcore]) from [<80073dec>] (irq_thread+0x10c/0x160)
[<80073dec>] (irq_thread+0x10c/0x160) from [<80043220>] (kthread+0xb4/0xb8)
[<80043220>] (kthread+0xb4/0xb8) from [<8000e118>] (ret_from_fork+0x14/0x3c)
---[ end trace c223e9937b562fe2 ]---
wlcore: Hardware recovery in progress. FW ver: Rev 8.8.0.0.13
wlcore: pc: 0x1181f0, hint_sts: 0x00000000 count: 1
wlcore: down
wlcore: down
ieee80211 phy0: Hardware restart was requested
wlan0: deauthenticating from 40:e3:d6:a2:94:08 by local choice (reason=3)
cfg80211: Calling CRDA to update world regulatory domain
wlcore: PHY firmware version: Rev 8.2.0.0.195
wlcore: firmware booted (Rev 8.8.0.0.13)
br0: port 2(wlan0) entered disabled state
OK
OK
0
OK
OK
OK
OK
OK
OK
OK
OK
OK
OK
wpa_state=SCANNING
address=78:a5:04:26:72:44
uuid=116c5382-4500-506d-8fc8-202bbd61bdb4
udhcpc (v1.22.1) started
wlan0: authenticate with a2:f8:95:28:56:3d
wlan0: send auth to a2:f8:95:28:56:3d (try 1/3)
wlan0: authenticated
wlan0: associate with a2:f8:95:28:56:3d (try 1/3)
wlan0: RX AssocResp from a2:f8:95:28:56:3d (capab=0x431 status=0 aid=1)
IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
wlan0: associated
Sending discover...
Sending discover...
wlan0: deauthenticating from a2:f8:95:28:56:3d by local choice (reason=3)
cfg80211: Calling CRDA to update world regulatory domain
wlan0: authenticate with a2:f8:95:28:56:3d
wlan0: send auth to a2:f8:95:28:56:3d (try 1/3)
wlan0: authenticated
wlan0: associate with a2:f8:95:28:56:3d (try 1/3)
wlan0: RX AssocResp from a2:f8:95:28:56:3d (capab=0x431 status=0 aid=1)
wlan0: associated
Sending discover...
wlan0: deauthenticating from a2:f8:95:28:56:3d by local choice (reason=3)
cfg80211: Calling CRDA to update world regulatory domain
wlan0: authenticate with a2:f8:95:28:56:3d
wlan0: send auth to a2:f8:95:28:56:3d (try 1/3)
wlan0: authenticated
wlan0: associate with a2:f8:95:28:56:3d (try 1/3)
wlan0: RX AssocResp from a2:f8:95:28:56:3d (capab=0x431 status=0 aid=1)
wlan0: associated
Sending discover...

****These logs in a loop****


Logs in /var/log/messages:

root@imx6slzbha:~# tail -f /var/log/messages
Dec 14 18:13:07 imx6slzbha user.info kernel: wlcore: pc: 0x1181f0, hint_sts: 0x00000000 count: 1
Dec 14 18:13:07 imx6slzbha user.info kernel: wlcore: down
Dec 14 18:13:07 imx6slzbha user.info kernel: wlcore: down
Dec 14 18:13:07 imx6slzbha user.info kernel: ieee80211 phy0: Hardware restart was requested
Dec 14 18:13:07 imx6slzbha user.info kernel: wlan0: deauthenticating from 40:e3:d6:a2:94:08 by local choice (reason=3)
Dec 14 18:13:07 imx6slzbha user.info kernel: cfg80211: Calling CRDA to update world regulatory domain
Dec 14 18:13:08 imx6slzbha user.info kernel: wlcore: PHY firmware version: Rev 8.2.0.0.195
Dec 14 18:13:08 imx6slzbha user.info kernel: wlcore: firmware booted (Rev 8.8.0.0.13)
Dec 14 18:13:08 imx6slzbha user.info kernel: br0: port 2(wlan0) entered disabled state
Dec 14 18:13:09 imx6slzbha daemon.info telnetd[477]: child process 478 exited: 0
Dec 14 18:14:02 imx6slzbha user.info kernel: wlan0: authenticate with a2:f8:95:28:56:3d
Dec 14 18:14:02 imx6slzbha user.info kernel: wlan0: send auth to a2:f8:95:28:56:3d (try 1/3)
Dec 14 18:14:02 imx6slzbha user.info kernel: wlan0: authenticated
Dec 14 18:14:02 imx6slzbha user.info kernel: wlan0: associate with a2:f8:95:28:56:3d (try 1/3)
Dec 14 18:14:02 imx6slzbha user.info kernel: wlan0: RX AssocResp from a2:f8:95:28:56:3d (capab=0x431 status=0 aid=1)
Dec 14 18:14:02 imx6slzbha user.info kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Dec 14 18:14:02 imx6slzbha user.info kernel: wlan0: associated
Dec 14 18:14:12 imx6slzbha user.info kernel: wlan0: deauthenticating from a2:f8:95:28:56:3d by local choice (reason=3)
Dec 14 18:14:12 imx6slzbha user.info kernel: cfg80211: Calling CRDA to update world regulatory domain
Dec 14 18:14:13 imx6slzbha user.info kernel: wlan0: authenticate with a2:f8:95:28:56:3d
Dec 14 18:14:13 imx6slzbha user.info kernel: wlan0: send auth to a2:f8:95:28:56:3d (try 1/3)
Dec 14 18:14:13 imx6slzbha user.info kernel: wlan0: authenticated
Dec 14 18:14:13 imx6slzbha user.info kernel: wlan0: associate with a2:f8:95:28:56:3d (try 1/3)
Dec 14 18:14:13 imx6slzbha user.info kernel: wlan0: RX AssocResp from a2:f8:95:28:56:3d (capab=0x431 status=0 aid=1)
Dec 14 18:14:13 imx6slzbha user.info kernel: wlan0: associated
Dec 14 18:14:23 imx6slzbha user.info kernel: wlan0: deauthenticating from a2:f8:95:28:56:3d by local choice (reason=3)
Dec 14 18:14:23 imx6slzbha user.info kernel: cfg80211: Calling CRDA to update world regulatory domain
Dec 14 18:14:34 imx6slzbha user.info kernel: wlan0: authenticate with a2:f8:95:28:56:3d
Dec 14 18:14:34 imx6slzbha user.info kernel: wlan0: send auth to a2:f8:95:28:56:3d (try 1/3)
Dec 14 18:14:34 imx6slzbha user.info kernel: wlan0: authenticated
Dec 14 18:14:34 imx6slzbha user.info kernel: wlan0: associate with a2:f8:95:28:56:3d (try 1/3)
Dec 14 18:14:34 imx6slzbha user.info kernel: wlan0: RX AssocResp from a2:f8:95:28:56:3d (capab=0x431 status=0 aid=1)
Dec 14 18:14:34 imx6slzbha user.info kernel: wlan0: associated
Dec 14 18:14:44 imx6slzbha user.info kernel: wlan0: deauthenticating from a2:f8:95:28:56:3d by local choice (reason=3)
Dec 14 18:14:44 imx6slzbha user.info kernel: cfg80211: Calling CRDA to update world regulatory domain
Dec 14 18:15:05 imx6slzbha user.info kernel: wlan0: authenticate with a2:f8:95:28:56:3d
Dec 14 18:15:05 imx6slzbha user.info kernel: wlan0: send auth to a2:f8:95:28:56:3d (try 1/3)
Dec 14 18:15:05 imx6slzbha user.info kernel: wlan0: authenticated
Dec 14 18:15:05 imx6slzbha user.info kernel: wlan0: associate with a2:f8:95:28:56:3d (try 1/3)
Dec 14 18:15:05 imx6slzbha user.info kernel: wlan0: RX AssocResp from a2:f8:95:28:56:3d (capab=0x431 status=0 aid=1)
Dec 14 18:15:05 imx6slzbha user.info kernel: wlan0: associated


Logs using command: tcpdunmp -i wlan0 -vv

root@imx6slzbha:~# tcpdump -i wlan0 -vv
tcpdump: WARNING: wlan0: no IPv4 address assigned
tcpdump: listening on wlan0, link-type EN10MB (Ethernet), capture size 65535 bytes
18:15:07.740223 EAPOL key (3) v2, len 95
18:15:08.740905 EAPOL key (3) v2, len 95
18:15:09.036243 IP (tos 0x0, ttl 64, id 0, offset 0, flags [none], proto UDP (17), length 328)
0.0.0.0.bootpc > 255.255.255.255.bootps: [udp sum ok] BOOTP/DHCP, Request from 78:a5:04:26:72:44 (oui Unknown), length 300, xid 0x86bf064c, secs 120, Flags [none] (0x0000)
Client-Ethernet-Address 78:a5:04:26:72:44 (oui Unknown)
Vendor-rfc1048 Extensions
Magic Cookie 0x63825363
DHCP-Message Option 53, length 1: Discover
Client-ID Option 61, length 7: ether 78:a5:04:26:72:44
MSZ Option 57, length 2: 576
Parameter-Request Option 55, length 7:
Subnet-Mask, Default-Gateway, Domain-Name-Server, Hostname
Domain-Name, BR, NTP
Vendor-Class Option 60, length 12: "udhcp 1.22.1"
18:15:12.116044 IP (tos 0x0, ttl 64, id 0, offset 0, flags [none], proto UDP (17), length 328)
0.0.0.0.bootpc > 255.255.255.255.bootps: [udp sum ok] BOOTP/DHCP, Request from 78:a5:04:26:72:44 (oui Unknown), length 300, xid 0x86bf064c, secs 123, Flags [none] (0x0000)
Client-Ethernet-Address 78:a5:04:26:72:44 (oui Unknown)
Vendor-rfc1048 Extensions
Magic Cookie 0x63825363
DHCP-Message Option 53, length 1: Discover
Client-ID Option 61, length 7: ether 78:a5:04:26:72:44
MSZ Option 57, length 2: 576
Parameter-Request Option 55, length 7:
Subnet-Mask, Default-Gateway, Domain-Name-Server, Hostname
Domain-Name, BR, NTP
Vendor-Class Option 60, length 12: "udhcp 1.22.1"
18:15:37.114110 EAPOL key (3) v2, len 95
18:15:38.117270 EAPOL key (3) v2, len 95
18:15:38.326042 IP (tos 0x0, ttl 64, id 0, offset 0, flags [none], proto UDP (17), length 328)
0.0.0.0.bootpc > 255.255.255.255.bootps: [udp sum ok] BOOTP/DHCP, Request from 78:a5:04:26:72:44 (oui Unknown), length 300, xid 0x9a85150a, secs 149, Flags [none] (0x0000)
Client-Ethernet-Address 78:a5:04:26:72:44 (oui Unknown)
Vendor-rfc1048 Extensions
Magic Cookie 0x63825363
DHCP-Message Option 53, length 1: Discover
Client-ID Option 61, length 7: ether 78:a5:04:26:72:44
MSZ Option 57, length 2: 576
Parameter-Request Option 55, length 7:
Subnet-Mask, Default-Gateway, Domain-Name-Server, Hostname
Domain-Name, BR, NTP
Vendor-Class Option 60, length 12: "udhcp 1.22.1"
18:15:39.116131 EAPOL key (3) v2, len 95
18:15:40.117465 EAPOL key (3) v2, len 95
18:15:41.406052 IP (tos 0x0, ttl 64, id 0, offset 0, flags [none], proto UDP (17), length 328)
0.0.0.0.bootpc > 255.255.255.255.bootps: [udp sum ok] BOOTP/DHCP, Request from 78:a5:04:26:72:44 (oui Unknown), length 300, xid 0x9a85150a, secs 152, Flags [none] (0x0000)
Client-Ethernet-Address 78:a5:04:26:72:44 (oui Unknown)
Vendor-rfc1048 Extensions
Magic Cookie 0x63825363
DHCP-Message Option 53, length 1: Discover
Client-ID Option 61, length 7: ether 78:a5:04:26:72:44
MSZ Option 57, length 2: 576
Parameter-Request Option 55, length 7:
Subnet-Mask, Default-Gateway, Domain-Name-Server, Hostname
Domain-Name, BR, NTP
Vendor-Class Option 60, length 12: "udhcp 1.22.1"





./AP.sh (we are able to start AP mode successfully on wlan1).

PLease assist here,
why we are unable to get an IP on wlan0(STA mode).


Thanks
Ashish S

Hi Yordan,

A little modification in steps performed:
As observed, it may be the 4addr command which may be causing problems:
root@imx6slzbha:~# iw wlan0 set 4addr on
Please let me know if some more steps are required.


1. only eth0 and lo interfaces are up at boot.
2. we add bridge br0 first using:
brctl addbr br0, then
brctl addif br0 wlan0

and get logs: device wlan0 entered promiscuous mode

then we run our STA script:

wpa_supplicant -B -i wlan0 -c /etc/wpa_supplicant.conf
sleep 10
udhcpc -i wlan0


contents of wpa_supplicant.conf are:

ctrl_interface=/var/run/wpa_supplicant
ctrl_interface_group=0
update_config=1

network={
ssid="AJT"
psk="micromax"
proto=RSN
key_mgmt=WPA-PSK
pairwise=CCMP
auth_alg=OPEN
}

Logs:

root@imx6slzbha:~# brctl addbr br0
root@imx6slzbha:~# brctl addif br0 wlan0
can't add wlan0 to bridge br0: Operation not supported
root@imx6slzbha:~# iw wlan0 set 4addr on
root@imx6slzbha:~# brctl addif br0 wlan0
device wlan0 entered promiscuous mode
root@imx6slzbha:~# ifconfig br0 up
IPv6: ADDRCONF(NETDEV_UP): br0: link is not ready
root@imx6slzbha:~# sh STA.sh
Successfully initialized wpa_supplicant
wlcore: PHY firmware version: Rev 8.2.0.0.195
wlcore: firmware booted (Rev 8.8.0.0.13)
IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
br0: port 1(wlan0) entered forwarding state
br0: port 1(wlan0) entered forwarding state
br0: port 1(wlan0) entered disabled state
udhcpc (v1.22.1) started
Sending discover...
wlan0: authenticate with a2:f8:95:28:56:3d
wlan0: send auth to a2:f8:95:28:56:3d (try 1/3)
wlan0: authenticated
wlan0: associate with a2:f8:95:28:56:3d (try 1/3)
wlan0: RX AssocResp from a2:f8:95:28:56:3d (capab=0x431 status=0 aid=1)
IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
wlan0: associated
Sending discover...
Sending discover...
wlan0: deauthenticating from a2:f8:95:28:56:3d by local choice (reason=3)
cfg80211: Calling CRDA to update world regulatory domain
wlan0: authenticate with a2:f8:95:28:56:3d
wlan0: send auth to a2:f8:95:28:56:3d (try 1/3)
wlan0: authenticated
wlan0: associate with a2:f8:95:28:56:3d (try 1/3)
wlan0: RX AssocResp from a2:f8:95:28:56:3d (capab=0x431 status=0 aid=1)
wlan0: associated
...
....
..
we get these logs in a loop.


Please let us know if it is merely a configuration issue or wlcore driver issue.


Thanks
Ashish S