2005-01-22 16:05:24

by Pete Clements

[permalink] [raw]
Subject: ppp in 2.6.11-rc2 Badness in local_bh_enable at kernel/softirq.c

FYI:
Get the following with 2.6.11-rc2 and ppp (pppoe), continuous.
Non-ppp boxes fine. Last prior kernel 2.6.11-rc1-bk5 is good
(have not tried bk6 - bk9).

Badness in local_bh_enable at kernel/softirq.c:140
[local_bh_enable+44/104] local_bh_enable+0x2c/0x68
[ppp_async_push+358/372] ppp_async_push+0x166/0x174
[ppp_async_send+13/72] ppp_async_send+0xd/0x48
[ppp_push+70/176] ppp_push+0x46/0xb0
[ppp_send_frame+941/992] ppp_send_frame+0x3ad/0x3e0
[ppp_xmit_process+54/172] ppp_xmit_process+0x36/0xac
[ppp_start_xmit+459/520] ppp_start_xmit+0x1cb/0x208
[qdisc_restart+102/236] qdisc_restart+0x66/0xec
[dev_queue_xmit+199/476] dev_queue_xmit+0xc7/0x1dc
[ip_finish_output+340/456] ip_finish_output+0x154/0x1c8
[ip_forward_finish+0/68] ip_forward_finish+0x0/0x44
[ip_output+86/92] ip_output+0x56/0x5c
[ip_forward_finish+37/68] ip_forward_finish+0x25/0x44
[nf_hook_slow+165/240] nf_hook_slow+0xa5/0xf0
[ip_forward+492/572] ip_forward+0x1ec/0x23c
[ip_forward_finish+0/68] ip_forward_finish+0x0/0x44
[ip_rcv+881/1024] ip_rcv+0x371/0x400
[netif_receive_skb+284/332] netif_receive_skb+0x11c/0x14c
[process_backlog+112/248] process_backlog+0x70/0xf8
[net_rx_action+118/244] net_rx_action+0x76/0xf4
[gcc2_compiled.+58/136] __do_softirq+0x3a/0x88
[do_softirq+34/44] do_softirq+0x22/0x2c
[irq_exit+39/40] irq_exit+0x27/0x28
[do_IRQ+33/40] do_IRQ+0x21/0x28
[common_interrupt+26/32] common_interrupt+0x1a/0x20
[default_idle+0/52] default_idle+0x0/0x34
[default_idle+38/52] default_idle+0x26/0x34
[cpu_idle+69/88] cpu_idle+0x45/0x58
[stext+26/28] _stext+0x1a/0x1c
[start_kernel+326/328] start_kernel+0x146/0x148

--
Pete Clements


2005-01-22 16:40:07

by boris mogwitz

[permalink] [raw]
Subject: Re: ppp in 2.6.11-rc2 Badness in local_bh_enable at kernel/softirq.c

On Sat, Jan 22, 2005 at 11:04:57AM -0500, Pete Clements wrote:

> FYI:
> Get the following with 2.6.11-rc2 and ppp (pppoe), continuous.
> Non-ppp boxes fine. Last prior kernel 2.6.11-rc1-bk5 is good
> (have not tried bk6 - bk9).
>
> Badness in local_bh_enable at kernel/softirq.c:140
> [local_bh_enable+44/104] local_bh_enable+0x2c/0x68

I have a simmilar problem here when I try to access may
madwifi (cvs) device:

Badness in local_bh_enable at kernel/softirq.c:140
[<c011ace3>] local_bh_enable+0x83/0x90
[<e18ac6c4>] ath_hardstart+0x1b4/0x290 [ath_pci]
[<c029b966>] arp_create+0x1d6/0x250
[<c02723d3>] qdisc_restart+0x73/0x150
[<c026657e>] dev_queue_xmit+0x1be/0x260
[<c029b2b8>] arp_solicit+0x108/0x200
[<c026b494>] neigh_timer_handler+0x184/0x2e0
[<c026b310>] neigh_timer_handler+0x0/0x2e0
[<c011e9f1>] run_timer_softirq+0xe1/0x1d0
[<c0106f68>] timer_interrupt+0x48/0xf0
[<c011ac19>] __do_softirq+0x79/0x90
cut cut

mfg
boris


2005-01-23 08:26:33

by Johnny Strom

[permalink] [raw]
Subject: Re: ppp in 2.6.11-rc2 Badness in local_bh_enable at kernel/softirq.c


Hi

I have the same problem with 2.6.11-rc2 I am using ppp with rp-pppoe-3.5
it starts like this as soon as it brings upp the link.

This is from the booutup of my system:

Jan 21 19:45:14 mail kernel: [<c03650e8>] ip_send_reply+0x1e8/0x250
Jan 21 19:45:14 mail kernel: [<c0364e70>] ip_reply_glue_bits+0x0/0x90
Jan 21 19:45:14 mail kernel: [<c03a1f9b>] ip_nat_used_tuple+0x2b/0x40
Jan 21 19:45:14 mail kernel: [<c03a254f>] ip_nat_setup_info+0xbf/0x300
Jan 21 19:45:14 mail kernel: [<c0379591>] tcp_v4_send_reset+0xf1/0x150
Jan 21 19:45:15 mail kernel: [<c037abef>] tcp_v4_rcv+0x43f/0x7e0
Jan 21 19:45:15 mail kernel: [<c0360180>] ip_local_deliver_finish+0x0/0x170
Jan 21 19:45:15 mail kernel: [<c036023c>]
ip_local_deliver_finish+0xbc/0x170
Jan 21 19:45:15 mail kernel: [<c0360180>] ip_local_deliver_finish+0x0/0x170
Jan 21 19:45:15 mail kernel: [<c0356bea>] nf_hook_slow+0x11a/0x180
Jan 21 19:45:15 mail kernel: [<c0360180>] ip_local_deliver_finish+0x0/0x170
Jan 21 19:45:15 mail kernel: [<c03602f0>] ip_rcv_finish+0x0/0x250
Jan 21 19:45:15 mail kernel: [<c035ff59>] ip_local_deliver+0x59/0x60
Jan 21 19:45:15 mail kernel: [<c0360180>] ip_local_deliver_finish+0x0/0x170
Jan 21 19:45:15 mail kernel: [<c03603d9>] ip_rcv_finish+0xe9/0x250
Jan 21 19:45:16 mail kernel: [<c03602f0>] ip_rcv_finish+0x0/0x250
Jan 21 19:45:16 mail kernel: [<c0356bea>] nf_hook_slow+0x11a/0x180
Jan 21 19:45:16 mail kernel: [<c03602f0>] ip_rcv_finish+0x0/0x250
Jan 21 19:45:16 mail kernel: [<c03600cd>] ip_rcv+0x16d/0x220
Jan 21 19:45:16 mail kernel: [<c03602f0>] ip_rcv_finish+0x0/0x250
Jan 21 19:45:16 mail kernel: [<c034b468>] netif_receive_skb+0x128/0x1b0
Jan 21 19:45:16 mail kernel: [<c02847df>] ppp_input+0xff/0x1a0
Jan 21 19:45:17 mail kernel: [<c034b563>] process_backlog+0x73/0x100
Jan 21 19:45:17 mail kernel: [<c034b65a>] net_rx_action+0x6a/0xf0
Jan 21 19:45:17 mail kernel: [<c01197b8>] __do_softirq+0x78/0x90
Jan 21 19:45:17 mail kernel: [<c01197f6>] do_softirq+0x26/0x30
Jan 21 19:45:17 mail kernel: [<c011985c>] local_bh_enable+0x5c/0x90
Jan 21 19:45:17 mail kernel: [<c03b35f4>] packet_poll+0x84/0xa0
Jan 21 19:45:17 mail kernel: [<c03419b9>] sock_poll+0x29/0x40
Jan 21 19:45:18 mail kernel: [<c01666d2>] do_select+0x272/0x2e0
Jan 21 19:45:18 mail kernel: [<c01662b0>] __pollwait+0x0/0xd0
Jan 21 19:45:18 mail kernel: [<c0166a2f>] sys_select+0x2bf/0x4d0
Jan 21 19:45:18 mail kernel: [<c010277d>] sysenter_past_esp+0x52/0x75
Jan 21 19:45:18 mail kernel: Badness in local_bh_enable at
kernel/softirq.c:140
Jan 21 19:45:18 mail kernel: [<c0119887>] local_bh_enable+0x87/0x90
Jan 21 19:45:18 mail kernel: [<c02884db>] ppp_async_push+0xab/0x180
Jan 21 19:45:18 mail kernel: [<c02ae931>] ide_intr+0x141/0x1b0
Jan 21 19:45:18 mail kernel: [<c02883f5>] ppp_async_send+0x15/0x50
Jan 21 19:45:18 mail kernel: [<c02845d5>] ppp_push+0xf5/0x100
Jan 21 19:45:18 mail kernel: [<c028421d>] ppp_send_frame+0x25d/0x520
Jan 21 19:45:18 mail kernel: [<c039a350>] tcp_in_window+0x470/0x480
Jan 21 19:45:18 mail kernel: [<c0283f5f>] ppp_xmit_process+0x7f/0xe0
Jan 21 19:45:18 mail kernel: [<c0283be8>] ppp_start_xmit+0xd8/0x240
Jan 21 19:45:18 mail kernel: [<c0357cf0>] qdisc_restart+0x70/0x180
Jan 21 19:45:19 mail kernel: [<c034af2f>] dev_queue_xmit+0x1df/0x2a0
Jan 21 19:45:19 mail kernel: [<c03652d6>] ip_finish_output2+0x106/0x1f0
Jan 21 19:45:19 mail kernel: [<c03651d0>] ip_finish_output2+0x0/0x1f0
Jan 21 19:45:19 mail kernel: [<c03651d0>] ip_finish_output2+0x0/0x1f0
Jan 21 19:45:19 mail kernel: [<c0356bea>] nf_hook_slow+0x11a/0x180
Jan 21 19:45:19 mail kernel: [<c03651d0>] ip_finish_output2+0x0/0x1f0
Jan 21 19:45:19 mail kernel: [<c03651a0>] dst_output+0x0/0x30
Jan 21 19:45:19 mail kernel: [<c0362d4a>] ip_finish_output+0x4a/0x50
Jan 21 19:45:19 mail kernel: [<c03651d0>] ip_finish_output2+0x0/0x1f0
Jan 21 19:45:19 mail kernel: [<c03651b4>] dst_output+0x14/0x30
Jan 21 19:45:19 mail kernel: [<c0356bea>] nf_hook_slow+0x11a/0x180
Jan 21 19:45:19 mail kernel: [<c03651a0>] dst_output+0x0/0x30
Jan 21 19:45:19 mail kernel: [<c0364c61>]
ip_push_pending_frames+0x2f1/0x420
Jan 21 19:45:19 mail kernel: [<c03651a0>] dst_output+0x0/0x30
Jan 21 19:45:19 mail kernel: [<c0362d4a>] ip_finish_output+0x4a/0x50
Jan 21 19:45:19 mail kernel: [<c03651d0>] ip_finish_output2+0x0/0x1f0
Jan 21 19:45:19 mail kernel: [<c03651b4>] dst_output+0x14/0x30
Jan 21 19:45:19 mail kernel: [<c0356bea>] nf_hook_slow+0x11a/0x180
Jan 21 19:45:19 mail kernel: [<c03651a0>] dst_output+0x0/0x30
Jan 21 19:45:19 mail kernel: [<c0364c61>]
ip_push_pending_frames+0x2f1/0x420
Jan 21 19:45:19 mail kernel: [<c03651a0>] dst_output+0x0/0x30
Jan 21 19:45:19 mail kernel: [<c03650e8>] ip_send_reply+0x1e8/0x250
Jan 21 19:45:19 mail kernel: [<c0364e70>] ip_reply_glue_bits+0x0/0x90
Jan 21 19:45:20 mail kernel: [<c03a1f9b>] ip_nat_used_tuple+0x2b/0x40
Jan 21 19:45:20 mail kernel: [<c03a254f>] ip_nat_setup_info+0xbf/0x300
Jan 21 19:45:20 mail kernel: [<c0379591>] tcp_v4_send_reset+0xf1/0x150
Jan 21 19:45:20 mail kernel: [<c037abef>] tcp_v4_rcv+0x43f/0x7e0
Jan 21 19:45:20 mail kernel: [<c0360180>] ip_local_deliver_finish+0x0/0x170
Jan 21 19:45:20 mail kernel: [<c0360180>] ip_local_deliver_finish+0x0/0x170
Jan 21 19:45:21 mail kernel: [<c036023c>]
ip_local_deliver_finish+0xbc/0x170
LPF/eth1/52:54:4c:1a:14:8b/192.168.1.0/24
Jan 21 19:45:21 mail kernel: [<c0360180>] ip_local_deliver_finish+0x0/0x170
Jan 21 19:45:21 mail dhcpd:
Jan 21 19:45:21 mail kernel: [<c0356bea>] nf_hook_slow+0x11a/0x180
Jan 21 19:45:21 mail kernel: [<c0360180>] ip_local_deliver_finish+0x0/0x170
Jan 21 19:45:21 mail kernel: [<c03602f0>] ip_rcv_finish+0x0/0x250
Jan 21 19:45:21 mail kernel: [<c035ff59>] ip_local_deliver+0x59/0x60
Jan 21 19:45:22 mail kernel: [<c0360180>] ip_local_deliver_finish+0x0/0x170
Jan 21 19:45:22 mail kernel: [<c03603d9>] ip_rcv_finish+0xe9/0x250
Jan 21 19:45:22 mail kernel: [<c03602f0>] ip_rcv_finish+0x0/0x250
Jan 21 19:45:22 mail kernel: [<c0356bea>] nf_hook_slow+0x11a/0x180
Jan 21 19:45:22 mail kernel: [<c03602f0>] ip_rcv_finish+0x0/0x250
Jan 21 19:45:22 mail kernel: [<c03600cd>] ip_rcv+0x16d/0x220
Jan 21 19:45:23 mail kernel: [<c03602f0>] ip_rcv_finish+0x0/0x250
Jan 21 19:45:23 mail kernel: [<c034b468>] netif_receive_skb+0x128/0x1b0



2005-01-23 18:05:32

by Rogério Brito

[permalink] [raw]
Subject: Re: ppp in 2.6.11-rc2 Badness in local_bh_enable at kernel/softirq.c

On Jan 23 2005, Johnny Strom wrote:
> I have the same problem with 2.6.11-rc2 I am using ppp with rp-pppoe-3.5
> it starts like this as soon as it brings upp the link.

I am also seeing this "Badness in local_bh_enable at kernel/softirq.c:140"
message spamming my logs. It is quite scary, might I add. I have only seen
it with 2.6.11-rc2.

Aside from that, the system seems to be working fine, but I only noticed
this message for the last few hours. Before that, I run kernel 2.6.11-rc2
for 2 days straight and didn't see anything like that.

It seems to have started when I first loaded the netfilter modules. I can
provide more information. Just let me know what would be desired and I'll
try to get it.


Hope this helps, Rog?rio Brito.

--
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=
Rog?rio Brito - [email protected] - http://www.ime.usp.br/~rbrito
=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=-=