#341: Badness in local_bh_enable at kernel/softirq.c:140 , maybe related to
closed #154
----------------------------------+-----------------------------------------
Reporter: [EMAIL PROTECTED] | Owner:
Type: defect | Status: new
Priority: major | Milestone: version 0.9.0 - move to new
codebase
Component: madwifi: other | Version: trunk
Resolution: | Keywords:
----------------------------------+-----------------------------------------
Changes (by mrenzmann):
* milestone: => version 0.9.0 - move to new codebase
* version: => trunk
Old description:
> During heavy testing of madwifi-ng - "loopback test" using send2self
> patch and also real clients and ping flood with big packets, ftp end etc.
> - i have found this badness - it is quite reproducible, althrought
> sometimes it takes minutes and sometimes hours to get it. I use
> regdomain 96 on half of the cards - from ath0-3, which has many usable
> channels in 5Ghz band - either 5,4-5,7 and 5,7-5,8 which both is allowed
> here but with various txpower limits and other stuff and 5,1-5,3 which is
> allowed here only of indoor usage. On second half , ath4-7 i left
> regdomain untouched - so 0 , so only channels 5,1-5,3 and 5,7-5,8
> available.
>
> Devices are configured this way :[[BR]]ath0 essid bla in ap mode ip
> 192.168.170.1[[BR]]ath1-3 essid bla sta mode ips
> 192.168.171-3.1[[BR]]ath4 essid bla2 in ap mode ip
> 192.168.174.1[[BR]]ath5-7 essid bla2 in sta mode ips 192.168.175-7.1
>
> Machine is Sempron 32bit, kernel 2.6.15 nearly vanilla , madwifi
> changeset 1411 - should not matter, haven't seen change in code since
> that which is realted - IMHO . The badness is written into syslog and
> always for a short period of time the last two aths are
> dissassociated.[[BR]] Problem is best reproducible during following
> test:[[BR]]
> [[BR]]
> ping 192.168.170.1 -I 192.168.171.1 -f -s 1024 & > /dev/null [[BR]]
> .[[BR]]
> .[[BR]]
> ping 192.168.176.1 -I 192.168.177.1 -f -s 1024 & > /dev/null [[BR]]
> [[BR]]
> ath1-3 and also ath5 remains associated all the time and data flow
> without interruption. Will test it further with newest changeset and also
> with other kernel and on another HW. Cut from my syslog follows .... BTW,
> the radar stuff is strange too and i will probably open another ticket
> for that, very very bad is that it seems - HAL probably? - detects
> "radar" everywhere and continue switching channel till it settle in
> 5,7-5,8 band ...
>
> {{{
> Jan 26 13:17:38 tex1 kernel: wifi0: Changing to channel 132 (5660 MHz)
> Jan 26 13:17:38 tex1 kernel: Badness in local_bh_enable at
> kernel/softirq.c:140
> Jan 26 13:17:38 tex1 kernel: [<c011981d>] local_bh_enable+0x2a/0x61
> Jan 26 13:17:38 tex1 kernel: [<e891c90d>] ath_tx_draintxq+0xd0/0xfc
> [ath_pci]
> Jan 26 13:17:38 tex1 kernel: [<e891ca3d>] ath_draintxq+0xb8/0xc7
> [ath_pci]
> Jan 26 13:17:38 tex1 kernel: [<e891cd3f>] ath_chan_set+0xe9/0x306
> [ath_pci]
> Jan 26 13:17:38 tex1 kernel: [<e891d207>] ath_set_channel+0x1a/0x3d
> [ath_pci]
> Jan 26 13:17:38 tex1 kernel: [<e8963384>]
> ieee80211_beacon_update+0x1cc/0x740 [wlan]
> Jan 26 13:17:38 tex1 kernel: [<e8918660>]
> ath_beacon_generate+0x100/0x254 [ath_pci]
> Jan 26 13:17:38 tex1 kernel: [<e891893d>] ath_beacon_send+0x189/0x2e7
> [ath_pci]
> Jan 26 13:17:38 tex1 kernel: [<e8915b9b>] ath_intr+0x141/0x26f [ath_pci]
> Jan 26 13:17:38 tex1 kernel: [<c012e84b>] handle_IRQ_event+0x20/0x4c
> Jan 26 13:17:38 tex1 kernel: [<c012e8ca>] __do_IRQ+0x53/0x91
> Jan 26 13:17:38 tex1 kernel: [<c0104e89>] do_IRQ+0x19/0x24
> Jan 26 13:17:38 tex1 kernel: [<c01039fa>] common_interrupt+0x1a/0x20
> Jan 26 13:17:38 tex1 kernel: [<c0101047>] default_idle+0x2b/0x53
> Jan 26 13:17:38 tex1 kernel: [<c01010c5>] cpu_idle+0x42/0x57
> Jan 26 13:17:38 tex1 kernel: [<c03fc674>] start_kernel+0x171/0x173
> Jan 26 13:17:40 tex1 kernel: wifi0: Radar found on channel 132 (5660 MHz)
> Jan 26 13:17:40 tex1 kernel: wifi0: Changing to channel 40 (5200 MHz)
> Jan 26 13:17:40 tex1 kernel: wifi0: Radar found on channel 40 (5200 MHz)
> Jan 26 13:17:40 tex1 kernel: wifi0: Changing to channel 157 (5785 MHz)
> Jan 26 13:35:44 tex1 kernel: wifi0: Radar found on channel 112 (5560 MHz)
> Jan 26 13:35:44 tex1 kernel: wifi0: Changing to channel 108 (5540 MHz)
> Jan 26 13:35:44 tex1 kernel: Badness in local_bh_enable at
> kernel/softirq.c:140
> Jan 26 13:35:44 tex1 kernel: [<c011981d>] local_bh_enable+0x2a/0x61
> Jan 26 13:35:44 tex1 kernel: [<e891c90d>] ath_tx_draintxq+0xd0/0xfc
> [ath_pci]
> Jan 26 13:35:44 tex1 kernel: [<e891ca3d>] ath_draintxq+0xb8/0xc7
> [ath_pci]
> Jan 26 13:35:44 tex1 kernel: [<e891cd3f>] ath_chan_set+0xe9/0x306
> [ath_pci]
> Jan 26 13:35:44 tex1 kernel: [<e891d207>] ath_set_channel+0x1a/0x3d
> [ath_pci]
> Jan 26 13:35:44 tex1 kernel: [<e8963384>]
> ieee80211_beacon_update+0x1cc/0x740 [wlan]
> Jan 26 13:35:44 tex1 kernel: [<e8918660>]
> ath_beacon_generate+0x100/0x254 [ath_pci]
> Jan 26 13:35:44 tex1 kernel: [<e891893d>] ath_beacon_send+0x189/0x2e7
> [ath_pci]
> Jan 26 13:35:44 tex1 kernel: [<e8915b9b>] ath_intr+0x141/0x26f [ath_pci]
> Jan 26 13:35:44 tex1 kernel: [<c012e84b>] handle_IRQ_event+0x20/0x4c
> Jan 26 13:35:44 tex1 kernel: [<c012e8ca>] __do_IRQ+0x53/0x91
> Jan 26 13:35:44 tex1 kernel: [<c0104e89>] do_IRQ+0x19/0x24
> Jan 26 13:35:44 tex1 kernel: [<c01039fa>] common_interrupt+0x1a/0x20
> Jan 26 13:35:44 tex1 kernel: [<c011977c>] __do_softirq+0x2c/0x7d
> Jan 26 13:35:44 tex1 kernel: [<c01197ef>] do_softirq+0x22/0x26
> Jan 26 13:35:44 tex1 kernel: [<c0104e8e>] do_IRQ+0x1e/0x24
> Jan 26 13:35:44 tex1 kernel: [<c01039fa>] common_interrupt+0x1a/0x20
> Jan 26 13:35:47 tex1 kernel: wifi0: Radar found on channel 108 (5540 MHz)
> Jan 26 13:35:47 tex1 kernel: wifi0: Changing to channel 34 (5170 MHz)
> Jan 26 13:35:51 tex1 kernel: wifi0: Radar found on channel 34 (5170 MHz)
> Jan 26 13:35:51 tex1 kernel: wifi0: Changing to channel 42 (5210 MHz)
> Jan 26 13:35:59 tex1 kernel: wifi0: Radar found on channel 42 (5210 MHz)
> Jan 26 13:35:59 tex1 kernel: wifi0: Changing to channel 44 (5220 MHz)
> Jan 26 13:36:04 tex1 kernel: wifi0: Radar found on channel 44 (5220 MHz)
> Jan 26 13:36:04 tex1 kernel: wifi0: Changing to channel 136 (5680 MHz)
> Jan 26 13:36:08 tex1 kernel: wifi0: Radar found on channel 136 (5680 MHz)
> Jan 26 13:36:08 tex1 kernel: wifi0: Changing to channel 52 (5260 MHz)
> Jan 26 13:36:09 tex1 kernel: wifi0: Radar found on channel 52 (5260 MHz)
> Jan 26 13:36:09 tex1 kernel: wifi0: Changing to channel 116 (5580 MHz)
> Jan 26 13:36:14 tex1 kernel: wifi0: Radar found on channel 116 (5580 MHz)
> Jan 26 13:36:14 tex1 kernel: wifi0: Changing to channel 36 (5180 MHz)
> Jan 26 13:36:15 tex1 kernel: wifi0: Radar found on channel 36 (5180 MHz)
> Jan 26 13:36:15 tex1 kernel: wifi0: Changing to channel 120 (5600 MHz)
> Jan 26 13:36:17 tex1 kernel: wifi0: Radar found on channel 120 (5600 MHz)
> Jan 26 13:36:17 tex1 kernel: wifi0: Changing to channel 124 (5620 MHz)
> Jan 26 13:36:20 tex1 kernel: wifi0: Radar found on channel 124 (5620 MHz)
> Jan 26 13:36:20 tex1 kernel: wifi0: Changing to channel 161 (5805 MHz)
> }}}
New description:
During heavy testing of madwifi-ng - "loopback test" using send2self
patch and also real clients and ping flood with big packets, ftp end etc.
- i have found this badness - it is quite reproducible, althrought
sometimes it takes minutes and sometimes hours to get it. I use regdomain
96 on half of the cards - from ath0-3, which has many usable channels in
5Ghz band - either 5,4-5,7 and 5,7-5,8 which both is allowed here but with
various txpower limits and other stuff and 5,1-5,3 which is allowed here
only of indoor usage. On second half , ath4-7 i left regdomain untouched -
so 0 , so only channels 5,1-5,3 and 5,7-5,8 available.
Devices are configured this way:[[BR]]ath0 essid bla in ap mode ip
192.168.170.1[[BR]]ath1-3 essid bla sta mode ips 192.168.171-3.1[[BR]]ath4
essid bla2 in ap mode ip 192.168.174.1[[BR]]ath5-7 essid bla2 in sta mode
ips 192.168.175-7.1
Machine is Sempron 32bit, kernel 2.6.15 nearly vanilla , madwifi changeset
1411 - should not matter, haven't seen change in code since that which is
realted - IMHO . The badness is written into syslog and always for a short
period of time the last two aths are dissassociated.[[BR]] Problem is best
reproducible during following test:[[BR]]
[[BR]]
ping 192.168.170.1 -I 192.168.171.1 -f -s 1024 & > /dev/null [[BR]]
.[[BR]]
.[[BR]]
ping 192.168.176.1 -I 192.168.177.1 -f -s 1024 & > /dev/null [[BR]]
[[BR]]
ath1-3 and also ath5 remains associated all the time and data flow without
interruption. Will test it further with newest changeset and also with
other kernel and on another HW. Cut from my syslog follows .... BTW, the
radar stuff is strange too and i will probably open another ticket for
that, very very bad is that it seems - HAL probably? - detects "radar"
everywhere and continue switching channel till it settle in 5,7-5,8 band
...
{{{
Jan 26 13:17:38 tex1 kernel: wifi0: Changing to channel 132 (5660 MHz)
Jan 26 13:17:38 tex1 kernel: Badness in local_bh_enable at
kernel/softirq.c:140
Jan 26 13:17:38 tex1 kernel: [<c011981d>] local_bh_enable+0x2a/0x61
Jan 26 13:17:38 tex1 kernel: [<e891c90d>] ath_tx_draintxq+0xd0/0xfc
[ath_pci]
Jan 26 13:17:38 tex1 kernel: [<e891ca3d>] ath_draintxq+0xb8/0xc7
[ath_pci]
Jan 26 13:17:38 tex1 kernel: [<e891cd3f>] ath_chan_set+0xe9/0x306
[ath_pci]
Jan 26 13:17:38 tex1 kernel: [<e891d207>] ath_set_channel+0x1a/0x3d
[ath_pci]
Jan 26 13:17:38 tex1 kernel: [<e8963384>]
ieee80211_beacon_update+0x1cc/0x740 [wlan]
Jan 26 13:17:38 tex1 kernel: [<e8918660>] ath_beacon_generate+0x100/0x254
[ath_pci]
Jan 26 13:17:38 tex1 kernel: [<e891893d>] ath_beacon_send+0x189/0x2e7
[ath_pci]
Jan 26 13:17:38 tex1 kernel: [<e8915b9b>] ath_intr+0x141/0x26f [ath_pci]
Jan 26 13:17:38 tex1 kernel: [<c012e84b>] handle_IRQ_event+0x20/0x4c
Jan 26 13:17:38 tex1 kernel: [<c012e8ca>] __do_IRQ+0x53/0x91
Jan 26 13:17:38 tex1 kernel: [<c0104e89>] do_IRQ+0x19/0x24
Jan 26 13:17:38 tex1 kernel: [<c01039fa>] common_interrupt+0x1a/0x20
Jan 26 13:17:38 tex1 kernel: [<c0101047>] default_idle+0x2b/0x53
Jan 26 13:17:38 tex1 kernel: [<c01010c5>] cpu_idle+0x42/0x57
Jan 26 13:17:38 tex1 kernel: [<c03fc674>] start_kernel+0x171/0x173
Jan 26 13:17:40 tex1 kernel: wifi0: Radar found on channel 132 (5660 MHz)
Jan 26 13:17:40 tex1 kernel: wifi0: Changing to channel 40 (5200 MHz)
Jan 26 13:17:40 tex1 kernel: wifi0: Radar found on channel 40 (5200 MHz)
Jan 26 13:17:40 tex1 kernel: wifi0: Changing to channel 157 (5785 MHz)
Jan 26 13:35:44 tex1 kernel: wifi0: Radar found on channel 112 (5560 MHz)
Jan 26 13:35:44 tex1 kernel: wifi0: Changing to channel 108 (5540 MHz)
Jan 26 13:35:44 tex1 kernel: Badness in local_bh_enable at
kernel/softirq.c:140
Jan 26 13:35:44 tex1 kernel: [<c011981d>] local_bh_enable+0x2a/0x61
Jan 26 13:35:44 tex1 kernel: [<e891c90d>] ath_tx_draintxq+0xd0/0xfc
[ath_pci]
Jan 26 13:35:44 tex1 kernel: [<e891ca3d>] ath_draintxq+0xb8/0xc7
[ath_pci]
Jan 26 13:35:44 tex1 kernel: [<e891cd3f>] ath_chan_set+0xe9/0x306
[ath_pci]
Jan 26 13:35:44 tex1 kernel: [<e891d207>] ath_set_channel+0x1a/0x3d
[ath_pci]
Jan 26 13:35:44 tex1 kernel: [<e8963384>]
ieee80211_beacon_update+0x1cc/0x740 [wlan]
Jan 26 13:35:44 tex1 kernel: [<e8918660>] ath_beacon_generate+0x100/0x254
[ath_pci]
Jan 26 13:35:44 tex1 kernel: [<e891893d>] ath_beacon_send+0x189/0x2e7
[ath_pci]
Jan 26 13:35:44 tex1 kernel: [<e8915b9b>] ath_intr+0x141/0x26f [ath_pci]
Jan 26 13:35:44 tex1 kernel: [<c012e84b>] handle_IRQ_event+0x20/0x4c
Jan 26 13:35:44 tex1 kernel: [<c012e8ca>] __do_IRQ+0x53/0x91
Jan 26 13:35:44 tex1 kernel: [<c0104e89>] do_IRQ+0x19/0x24
Jan 26 13:35:44 tex1 kernel: [<c01039fa>] common_interrupt+0x1a/0x20
Jan 26 13:35:44 tex1 kernel: [<c011977c>] __do_softirq+0x2c/0x7d
Jan 26 13:35:44 tex1 kernel: [<c01197ef>] do_softirq+0x22/0x26
Jan 26 13:35:44 tex1 kernel: [<c0104e8e>] do_IRQ+0x1e/0x24
Jan 26 13:35:44 tex1 kernel: [<c01039fa>] common_interrupt+0x1a/0x20
Jan 26 13:35:47 tex1 kernel: wifi0: Radar found on channel 108 (5540 MHz)
Jan 26 13:35:47 tex1 kernel: wifi0: Changing to channel 34 (5170 MHz)
Jan 26 13:35:51 tex1 kernel: wifi0: Radar found on channel 34 (5170 MHz)
Jan 26 13:35:51 tex1 kernel: wifi0: Changing to channel 42 (5210 MHz)
Jan 26 13:35:59 tex1 kernel: wifi0: Radar found on channel 42 (5210 MHz)
Jan 26 13:35:59 tex1 kernel: wifi0: Changing to channel 44 (5220 MHz)
Jan 26 13:36:04 tex1 kernel: wifi0: Radar found on channel 44 (5220 MHz)
Jan 26 13:36:04 tex1 kernel: wifi0: Changing to channel 136 (5680 MHz)
Jan 26 13:36:08 tex1 kernel: wifi0: Radar found on channel 136 (5680 MHz)
Jan 26 13:36:08 tex1 kernel: wifi0: Changing to channel 52 (5260 MHz)
Jan 26 13:36:09 tex1 kernel: wifi0: Radar found on channel 52 (5260 MHz)
Jan 26 13:36:09 tex1 kernel: wifi0: Changing to channel 116 (5580 MHz)
Jan 26 13:36:14 tex1 kernel: wifi0: Radar found on channel 116 (5580 MHz)
Jan 26 13:36:14 tex1 kernel: wifi0: Changing to channel 36 (5180 MHz)
Jan 26 13:36:15 tex1 kernel: wifi0: Radar found on channel 36 (5180 MHz)
Jan 26 13:36:15 tex1 kernel: wifi0: Changing to channel 120 (5600 MHz)
Jan 26 13:36:17 tex1 kernel: wifi0: Radar found on channel 120 (5600 MHz)
Jan 26 13:36:17 tex1 kernel: wifi0: Changing to channel 124 (5620 MHz)
Jan 26 13:36:20 tex1 kernel: wifi0: Radar found on channel 124 (5620 MHz)
Jan 26 13:36:20 tex1 kernel: wifi0: Changing to channel 161 (5805 MHz)
}}}
--
Ticket URL: <http://madwifi.org/ticket/341>
MadWifi <http://madwifi.org/>
Multiband Atheros Driver for Wireless Fidelity
