On Wed, 2013-12-18 at 14:14 +0400, Eugene Shatokhin wrote:
> Interesting.
>
> Could you also add a call to dump_stack() in your patch so as to get the
> call trace in the system log when that strange event happens, not only
> the warning?
>
> It may help to see what actually happens.
>
Hi again
I added dump_stack():
------------------------------------------------------------
diff -Naur linux-3.10.10.orig/net/core/skbuff.c
linux-3.10.10/net/core/skbuff.c
--- linux-3.10.10.orig/net/core/skbuff.c 2013-08-29
18:47:51.000000000 +0200
+++ linux-3.10.10/net/core/skbuff.c 2013-12-18 12:16:37.946854138
+0100
@@ -1282,8 +1282,11 @@
SKB_LINEAR_ASSERT(skb);
skb->tail += len;
skb->len += len;
- if (unlikely(skb->tail > skb->end))
- skb_over_panic(skb, len, __builtin_return_address(0));
+ if (unlikely(skb->tail > skb->end)) {
+ net_warn_ratelimited("Warning: skb_put l:%i t:%#lx e:%#lx",
+ len, (unsigned long)skb->tail,(unsigned long)skb->end);
+ dump_stack();
+ }
return tmp;
}
EXPORT_SYMBOL(skb_put);
------------------------------------------------------------
And here is the dump in my log: (sorry for line wrapping)
------------------------------------------------------------
2013-12-18 11:28:01.010902 udhcpc[973]:[inf] udhcpc (v1.19.4) started
2013-12-18 11:28:01.017578 udhcpc[973]:[inf] Sending discover...
2013-12-18 11:28:03.930506 kernel:[inf][ 42.749365] e1000e: eth0 NIC
Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx
2013-12-18 11:28:03.930564 kernel:[inf][ 42.749735] IPv6:
ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
2013-12-18 11:28:04.021238 udhcpc[973]:[inf] Sending discover...
2013-12-18 11:28:05.790251 kernel:[war] [ 44.600750] skbuff: Warning:
skb_put l:9250 t:0xdd425c62 e:0xdd423f40
2013-12-18 11:28:05.790848 kernel:[war] [ 44.600855] CPU: 0 PID: 0
Comm: swapper/0 Tainted: P O 3.10.10-Unisensor #1
2013-12-18 11:28:05.791004 kernel:[war] [ 44.601606] Hardware name: To
Be Filled By O.E.M. To Be Filled By O.E.M./nanoETXexpress-SP, BIOS
NOW1R118 07/08/2011
2013-12-18 11:28:05.791105 kernel:[war] [ 44.601606] de22fe40
de22fe40 c156fe40 c1400088 c156fe54 c1305ee5 de3e0400 00000000
2013-12-18 11:28:05.791181 kernel:[war] [ 44.601606] 000005f2
c156fea8 e033aac2 de128c64 00000040 c156feb8 e034df00 00000002
2013-12-18 11:28:05.791256 kernel:[war] [ 44.601606] de80a780
de3e0000 0000284c 00000002 206f6863 00002422 de80a770 de22fe40
2013-12-18 11:28:05.791330 kernel:[war] [ 44.601606] Call Trace:
2013-12-18 11:28:05.791403 kernel:[war] [ 44.601606] [<c1400088>]
dump_stack+0x16/0x1e
2013-12-18 11:28:05.791474 kernel:[war] [ 44.601606] [<c1305ee5>]
skb_put+0x54/0x5e
2013-12-18 11:28:05.791546 kernel:[war] [ 44.601606] [<e033aac2>]
e1000_clean_rx_irq+0x1e2/0x2ab [e1000e]
2013-12-18 11:28:05.791618 kernel:[war] [ 44.601606] [<e033fa75>]
e1000e_poll+0x6f/0x220 [e1000e]
2013-12-18 11:28:05.791689 kernel:[war] [ 44.601606] [<c130fc3f>]
net_rx_action+0x86/0x14b
2013-12-18 11:28:05.791762 kernel:[war] [ 44.601606] [<c102d4cb>]
__do_softirq+0x93/0x139
2013-12-18 11:28:05.791834 kernel:[war] [ 44.601606] [<c102d625>]
irq_exit+0x36/0x70
2013-12-18 11:28:05.791905 kernel:[war] [ 44.601606] [<c1002510>]
do_IRQ+0x7c/0x90
2013-12-18 11:28:05.791977 kernel:[war] [ 44.601606] [<c1406aec>]
common_interrupt+0x2c/0x31
2013-12-18 11:28:05.792048 kernel:[war] [ 44.601606] [<c12e7d57>] ?
cpuidle_enter_state+0x38/0xb2
2013-12-18 11:28:05.792139 kernel:[war] [ 44.601606] [<c12e7e7c>]
cpuidle_idle_call+0xab/0xdf
2013-12-18 11:28:05.792212 kernel:[war] [ 44.601606] [<c10066be>]
arch_cpu_idle+0x8/0x17
2013-12-18 11:28:05.792284 kernel:[war] [ 44.601606] [<c1050b40>]
cpu_startup_entry+0x91/0xf1
2013-12-18 11:28:05.793370 kernel:[war] [ 44.601606] [<c13f5721>]
rest_init+0x5d/0x5f
2013-12-18 11:28:05.793733 kernel:[war] [ 44.601606] [<c15b38c7>]
start_kernel+0x2b9/0x2be
2013-12-18 11:28:05.793919 kernel:[war] [ 44.601606] [<c15b32a3>]
i386_start_kernel+0x79/0x7d
2013-12-18 11:28:06.150145 kernel:[war][ 44.960751] skbuff: Warning:
skb_put l:30305 t:0xdd4c16a1 e:0xdd4ba740
2013-12-18 11:28:06.150461 kernel:[war][ 44.960856] CPU: 0 PID: 0
Comm: swapper/0 Tainted: P O 3.10.10-Unisensor #1
2013-12-18 11:28:06.150601 kernel:[war][ 44.961612] Hardware name: To
Be Filled By O.E.M. To Be Filled By O.E.M./nanoETXexpress-SP, BIOS
NOW1R118 07/08/2011
2013-12-18 11:28:06.150741 kernel:[war][ 44.961612] de335780 de335780
c156fe40 c1400088 c156fe54 c1305ee5 de3e0400 00000000
2013-12-18 11:28:06.150885 kernel:[war][ 44.961612] 000005f2 c156fea8
e033aac2 de128c64 00000040 c156feb8 e034e2c0 00000002
2013-12-18 11:28:06.151020 kernel:[war][ 44.961612] de80a960 de3e0000
00007a8b 00000002 68206577 00007661 de80a950 de335780
2013-12-18 11:28:06.151174 kernel:[war][ 44.961612] Call Trace:
2013-12-18 11:28:06.151334 kernel:[war][ 44.961612] [<c1400088>]
dump_stack+0x16/0x1e
2013-12-18 11:28:06.151471 kernel:[war][ 44.961612] [<c1305ee5>]
skb_put+0x54/0x5e
2013-12-18 11:28:06.151606 kernel:[war][ 44.961612] [<e033aac2>]
e1000_clean_rx_irq+0x1e2/0x2ab [e1000e]
2013-12-18 11:28:06.151751 kernel:[war][ 44.961612] [<e033fa75>]
e1000e_poll+0x6f/0x220 [e1000e]
2013-12-18 11:28:06.151886 kernel:[war][ 44.961612] [<c130fc3f>]
net_rx_action+0x86/0x14b
2013-12-18 11:28:06.152021 kernel:[war][ 44.961612] [<c102d4cb>]
__do_softirq+0x93/0x139
2013-12-18 11:28:06.152178 kernel:[war][ 44.961612] [<c102d625>]
irq_exit+0x36/0x70
2013-12-18 11:28:06.152313 kernel:[war][ 44.961612] [<c1002510>]
do_IRQ+0x7c/0x90
2013-12-18 11:28:06.152457 kernel:[war][ 44.961612] [<c1406aec>]
common_interrupt+0x2c/0x31
2013-12-18 11:28:06.152606 kernel:[war][ 44.961612] [<c12e7d57>] ?
cpuidle_enter_state+0x38/0xb2
2013-12-18 11:28:06.152741 kernel:[war][ 44.961612] [<c12e7e7c>]
cpuidle_idle_call+0xab/0xdf
2013-12-18 11:28:06.152876 kernel:[war][ 44.961612] [<c10066be>]
arch_cpu_idle+0x8/0x17
2013-12-18 11:28:06.153011 kernel:[war][ 44.961612] [<c1050b40>]
cpu_startup_entry+0x91/0xf1
2013-12-18 11:28:06.153169 kernel:[war][ 44.961612] [<c13f5721>]
rest_init+0x5d/0x5f
2013-12-18 11:28:06.153341 kernel:[war][ 44.961612] [<c15b38c7>]
start_kernel+0x2b9/0x2be
2013-12-18 11:28:06.153478 kernel:[war][ 44.961612] [<c15b32a3>]
i386_start_kernel+0x79/0x7d
2013-12-18 11:28:07.026350 udhcpc[973]:[inf] Sending discover...
2013-12-18 11:28:07.028786 udhcpc[973]:[inf] Sending select for
192.168.1.105...
2013-12-18 11:28:07.033035 udhcpc[973]:[inf] Lease of 192.168.1.105
obtained, lease time 691200
------------------------------------------------------------
Maybe this can help?
Best regards
Morten Østergaard
------------------------------------------------------------------------------
Rapidly troubleshoot problems before they affect your business. Most IT
organizations don't have a clear picture of how application performance
affects their revenue. With AppDynamics, you get 100% visibility into your
Java,.NET, & PHP application. Start your 15-day FREE TRIAL of AppDynamics Pro!
http://pubads.g.doubleclick.net/gampad/clk?id=84349831&iu=/4140/ostg.clktrk
_______________________________________________
E1000-devel mailing list
E1000-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/e1000-devel
To learn more about Intel® Ethernet, visit
http://communities.intel.com/community/wired
