a lot of WARNING, nf_xfrm_me_harder in 4.15.x
From: Denys Fedoryshchenko <hidden>
Date: 2018-02-18 22:53:59
Is there any bug with that or it is just some sort of spam? Cause i am troubleshooting at same time "hard to catch" bug in ppp/pppoe Workload: pppoe bras I am going to try last stable 4.14.x after 1-2 days as well, but probably i noticed this message appeared there as well, under some conditions. [ 49.784216] WARNING: CPU: 4 PID: 0 at ./include/net/dst.h:256 nf_xfrm_me_harder+0x12d/0x2d7 [nf_nat] [ 49.784847] Modules linked in: pppoe pppox ppp_generic slhc netconsole configfs coretemp nf_nat_pptp nf_nat_proto_gre nf_conntrack_pptp nf_conntrack_proto_gre tun xt_TEE nf_dup_ipv4 xt_REDIRECT nf_nat_redirect xt_nat xt_TCPMSS ipt_REJECT nf_reject_ipv4 xt_set xt_string xt_connmark xt_DSCP xt_mark xt_tcpudp ip_set_hash_net ip_set_hash_ip ip_set nfnetlink iptable_mangle iptable_filter iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack ip_tables x_tables 8021q garp mrp stp llc ixgbe dca [ 49.786762] CPU: 4 PID: 0 Comm: swapper/4 Not tainted 4.15.4-build-0134 #2 [ 49.787104] Hardware name: HP ProLiant DL320e Gen8 v2, BIOS P80 04/02/2015 [ 49.787448] RIP: 0010:nf_xfrm_me_harder+0x12d/0x2d7 [nf_nat] [ 49.787782] RSP: 0018:ffff8803f23078e0 EFLAGS: 00010246 [ 49.788114] RAX: 0000000000000000 RBX: ffff8803d8acad00 RCX: 1ffff1007a875b00 [ 49.788463] RDX: 1ffff1007b159500 RSI: 0000000000000000 RDI: ffff8803d8acad48 [ 49.788818] RBP: ffff8803d43ada40 R08: ffffed007e460f27 R09: ffff8803f2307900 [ 49.789175] R10: ffffed007e460f26 R11: 0000000000000001 R12: 1ffff1007e460f1c [ 49.789528] R13: ffff8803d43ada98 R14: ffffffff83e2b600 R15: ffff8803d8acad80 [ 49.789881] FS: 0000000000000000(0000) GS:ffff8803f2300000(0000) knlGS:0000000000000000 [ 49.790500] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 49.790850] CR2: 00007f758e3aa490 CR3: 0000000445a0d001 CR4: 00000000001606e0 [ 49.791192] Call Trace: [ 49.791517] <IRQ> [ 49.791845] ? __nf_nat_decode_session+0x108/0x108 [nf_nat] [ 49.792180] ? nf_nat_ipv4_fn+0x33d/0x4df [nf_nat_ipv4] [ 49.792515] ? iptable_nat_ipv4_fn+0xc/0xc [iptable_nat] [ 49.792849] nf_nat_ipv4_out+0x235/0x305 [nf_nat_ipv4] [ 49.793183] ? iptable_nat_ipv4_local_fn+0xc/0xc [iptable_nat] [ 49.793519] nf_hook_slow+0xb1/0x11b [ 49.793850] ip_output+0x205/0x243 [ 49.794180] ? ip_mc_output+0x548/0x548 [ 49.794508] ? ip_fragment.constprop.5+0x197/0x197 [ 49.794841] ? iptable_filter_net_init+0x1a/0x1a [iptable_filter] [ 49.795173] ? nf_hook_slow+0xb1/0x11b [ 49.795504] ip_forward+0xe9c/0xecb [ 49.795836] ? ip_forward_finish+0x110/0x110 [ 49.796166] ? ip_frag_mem+0x3d/0x3d [ 49.796493] ? ip_rcv_finish+0xcf8/0xd91 [ 49.796830] ip_rcv+0x985/0xa12 [ 49.797178] ? ip_local_deliver+0x225/0x225 [ 49.797536] ? ip_local_deliver_finish+0x599/0x599 [ 49.797893] ? ip_local_deliver+0x225/0x225 [ 49.798254] __netif_receive_skb_core+0x10ce/0x1c76 [ 49.798613] ? netif_set_xps_queue+0xbdb/0xbdb [ 49.798972] ? process_backlog+0x1c5/0x3c0 [ 49.799323] process_backlog+0x1c5/0x3c0 [ 49.799674] net_rx_action+0x3aa/0x840 [ 49.800026] ? napi_complete_done+0x22b/0x22b [ 49.800378] ? __tick_nohz_idle_enter+0x42b/0x9b3 [ 49.800733] ? get_cpu_iowait_time_us+0x16f/0x16f [ 49.801084] __do_softirq+0x17f/0x34a [ 49.801411] ? flush_smp_call_function_queue+0x16a/0x229 [ 49.801750] irq_exit+0x8f/0xf9 [ 49.802080] call_function_single_interrupt+0x92/0xa0 [ 49.802420] </IRQ> [ 49.802765] RIP: 0010:mwait_idle+0x99/0xac [ 49.803106] RSP: 0018:ffff8803f0317ef8 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff04 [ 49.803709] RAX: 0000000000000000 RBX: ffff8803f02e4240 RCX: 0000000000000000 [ 49.804042] RDX: 1ffff1007e05c848 RSI: 0000000000000000 RDI: 0000000000000000 [ 49.804372] RBP: ffff8803f02e4240 R08: 000055574f086bb0 R09: 00007f78bd996700 [ 49.804705] R10: ffff8803f0317dd0 R11: 0000000000000293 R12: 0000000000000000 [ 49.805038] R13: dffffc0000000000 R14: ffffed007e05c848 R15: ffff8803f02e4240 [ 49.805373] do_idle+0xe6/0x19a [ 49.805700] cpu_startup_entry+0x18/0x1a [ 49.806033] secondary_startup_64+0xa5/0xb0 [ 49.806359] Code: e0 07 83 c0 03 38 d0 7c 0c 84 d2 74 08 4c 89 ff e8 65 3f 26 e1 8b 83 80 00 00 00 85 c0 74 0c 8d 50 01 f0 41 0f b1 17 74 04 eb f0 <0f> ff 48 8d 7d 18 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 [ 49.807242] ---[ end trace 2654a347942730c3 ]--- [ 49.807580] dst_release: dst:0000000024366567 refcnt:-1 [ 164.894058] WARNING: CPU: 5 PID: 22617 at ./include/net/dst.h:256 nf_xfrm_me_harder+0x12d/0x2d7 [nf_nat] [ 164.894686] Modules linked in: pppoe pppox ppp_generic slhc netconsole configfs coretemp nf_nat_pptp nf_nat_proto_gre nf_conntrack_pptp nf_conntrack_proto_gre tun xt_TEE nf_dup_ipv4 xt_REDIRECT nf_nat_redirect xt_nat xt_TCPMSS ipt_REJECT nf_reject_ipv4 xt_set xt_string xt_connmark xt_DSCP xt_mark xt_tcpudp ip_set_hash_net ip_set_hash_ip ip_set nfnetlink iptable_mangle iptable_filter iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack ip_tables x_tables 8021q garp mrp stp llc ixgbe dca [ 164.896703] CPU: 5 PID: 22617 Comm: mdev Tainted: G W 4.15.4-build-0134 #2 [ 164.897316] Hardware name: HP ProLiant DL320e Gen8 v2, BIOS P80 04/02/2015 [ 164.897665] RIP: 0010:nf_xfrm_me_harder+0x12d/0x2d7 [nf_nat] [ 164.898011] RSP: 0000:ffff8803f23478e0 EFLAGS: 00010246 [ 164.898358] RAX: 0000000000000000 RBX: ffff8803d41d2440 RCX: 1ffff1006fc38b00 [ 164.898701] RDX: 1ffff1007a83a400 RSI: 0000000000000000 RDI: ffff8803d41d2488 [ 164.899051] RBP: ffff88037e1c5f40 R08: ffffed007e468f27 R09: ffff8803f2347900 [ 164.899396] R10: ffffed007e468f26 R11: 0000000000000001 R12: 1ffff1007e468f1c [ 164.899738] R13: ffff88037e1c5f98 R14: ffffffff83e2b600 R15: ffff8803d41d24c0 [ 164.900086] FS: 00007f435cdd3700(0000) GS:ffff8803f2340000(0000) knlGS:0000000000000000 [ 164.900702] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 164.901038] CR2: 000055e3e0d3e118 CR3: 0000000372d1d005 CR4: 00000000001606e0 [ 164.901383] Call Trace: [ 164.901727] <IRQ> [ 164.902074] ? __nf_nat_decode_session+0x108/0x108 [nf_nat] [ 164.902429] ? nf_nat_ipv4_fn+0x33d/0x4df [nf_nat_ipv4] [ 164.902775] ? iptable_nat_ipv4_fn+0xc/0xc [iptable_nat] [ 164.903118] nf_nat_ipv4_out+0x235/0x305 [nf_nat_ipv4] [ 164.903457] ? iptable_nat_ipv4_local_fn+0xc/0xc [iptable_nat] [ 164.903808] nf_hook_slow+0xb1/0x11b [ 164.904157] ip_output+0x205/0x243 [ 164.904499] ? ip_mc_output+0x548/0x548 [ 164.904842] ? ip_fragment.constprop.5+0x197/0x197 [ 164.905180] ? iptable_filter_net_init+0x1a/0x1a [iptable_filter] [ 164.905526] ? nf_hook_slow+0xb1/0x11b [ 164.905868] ip_forward+0xe9c/0xecb [ 164.906210] ? ip_forward_finish+0x110/0x110 [ 164.906563] ? ip_frag_mem+0x3d/0x3d [ 164.906916] ? ip_rcv_finish+0xcf8/0xd91 [ 164.907276] ip_rcv+0x985/0xa12 [ 164.907634] ? ip_local_deliver+0x225/0x225 [ 164.907989] ? ip_local_deliver_finish+0x599/0x599 [ 164.908346] ? ip_local_deliver+0x225/0x225 [ 164.908688] __netif_receive_skb_core+0x10ce/0x1c76 [ 164.909029] ? netif_set_xps_queue+0xbdb/0xbdb [ 164.909382] ? posix_cpu_timers_exit_group+0x53/0x53 [ 164.909727] ? native_sched_clock+0x76/0xf8 [ 164.910076] ? cyc2ns_read_end+0x1/0x1 [ 164.910421] ? process_backlog+0x1c5/0x3c0 [ 164.910776] process_backlog+0x1c5/0x3c0 [ 164.911131] net_rx_action+0x3aa/0x840 [ 164.911488] ? napi_complete_done+0x22b/0x22b [ 164.911844] __do_softirq+0x17f/0x34a [ 164.912198] ? flush_smp_call_function_queue+0x16a/0x229 [ 164.912544] irq_exit+0x8f/0xf9 [ 164.912884] call_function_single_interrupt+0x92/0xa0 [ 164.913226] </IRQ> [ 164.913558] RIP: 0010:unwind_next_frame+0x10bc/0x10d1 [ 164.913898] RSP: 0000:ffff8803704ef8e0 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff04 [ 164.914509] RAX: 0000000000000000 RBX: ffff8803704efa50 RCX: 0000000000000000 [ 164.914850] RDX: ffffed006e09df21 RSI: ffff8803704ef801 RDI: ffffed006e09df2d [ 164.915192] RBP: ffff8803704efa50 R08: 000000000000227e R09: ffff8803704efa50 [ 164.915539] R10: ffffed006e09df54 R11: 0000000000000001 R12: ffff8803704e8000 [ 164.915879] R13: ffff8803704efa88 R14: ffffffff840222ca R15: ffff8803704efa50 [ 164.916235] ? __unwind_start+0x2ad/0x561 [ 164.916588] ? deref_stack_reg+0xb7/0xb7 [ 164.916939] ? page_fault+0x48/0x50 [ 164.917284] __unwind_start+0x4fd/0x561 [ 164.917623] ? unwind_next_frame+0x10d1/0x10d1 [ 164.917968] ? __module_text_address+0xc/0x109 [ 164.918317] ? is_bpf_text_address+0x5/0xc [ 164.918672] ? kernel_text_address+0x3f/0x53 [ 164.919016] __save_stack_trace+0x62/0xba [ 164.919362] ? __save_stack_trace+0x62/0xba [ 164.919706] kasan_kmalloc.part.2+0x50/0xc5 [ 164.920051] ? kmem_cache_alloc+0xa6/0xae [ 164.920394] ? __anon_vma_prepare+0x51/0x3a3 [ 164.920737] ? __handle_mm_fault+0x1c44/0x1f15 [ 164.921081] ? __do_page_fault+0x49e/0x75d [ 164.921418] ? page_fault+0x49/0x50 [ 164.921758] ? kasan_kmalloc.part.2+0xb2/0xc5 [ 164.922102] ? radix_tree_next_chunk+0x300/0x5fb [ 164.922450] ? radix_tree_iter_delete+0x8b/0x8b [ 164.922792] ? alloc_set_pte+0x784/0xaff [ 164.929527] ? filemap_map_pages+0x47b/0x63a [ 164.929876] ? kasan_unpoison_shadow+0xf/0x2e [ 164.930217] ? kasan_kmalloc+0x61/0x8c [ 164.930563] kmem_cache_alloc+0xa6/0xae [ 164.930902] __anon_vma_prepare+0x85/0x3a3 [ 164.931253] __handle_mm_fault+0x1c44/0x1f15 [ 164.931600] ? vm_insert_page+0x445/0x445 [ 164.931947] __do_page_fault+0x49e/0x75d [ 164.932300] ? bad_area_access_error+0x1b4/0x1b4 [ 164.932647] ? SyS_mprotect+0x8/0xb [ 164.932983] ? do_syscall_64+0x1b1/0x31f [ 164.933328] ? page_fault+0x33/0x50 [ 164.933679] page_fault+0x49/0x50 [ 164.934012] RIP: 0033:0x55e3e0a8d350 [ 164.934350] RSP: 002b:00007ffde7b44750 EFLAGS: 00010203 [ 164.934352] Code: e0 07 83 c0 03 38 d0 7c 0c 84 d2 74 08 4c 89 ff e8 65 3f 26 e1 8b 83 80 00 00 00 85 c0 74 0c 8d 50 01 f0 41 0f b1 17 74 04 eb f0 <0f> ff 48 8d 7d 18 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 [ 164.935607] ---[ end trace 2654a347942730c4 ]--- [ 164.935966] dst_release: dst:0000000076d60cc7 refcnt:-1 [ 191.248582] WARNING: CPU: 6 PID: 46 at ./include/net/dst.h:256 nf_xfrm_me_harder+0x12d/0x2d7 [nf_nat] [ 191.249208] Modules linked in: pppoe pppox ppp_generic slhc netconsole configfs coretemp nf_nat_pptp nf_nat_proto_gre nf_conntrack_pptp nf_conntrack_proto_gre tun xt_TEE nf_dup_ipv4 xt_REDIRECT nf_nat_redirect xt_nat xt_TCPMSS ipt_REJECT nf_reject_ipv4 xt_set xt_string xt_connmark xt_DSCP xt_mark xt_tcpudp ip_set_hash_net ip_set_hash_ip ip_set nfnetlink iptable_mangle iptable_filter iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack ip_tables x_tables 8021q garp mrp stp llc ixgbe dca [ 191.251209] CPU: 6 PID: 46 Comm: ksoftirqd/6 Tainted: G W 4.15.4-build-0134 #2 [ 191.251823] Hardware name: HP ProLiant DL320e Gen8 v2, BIOS P80 04/02/2015 [ 191.252173] RIP: 0010:nf_xfrm_me_harder+0x12d/0x2d7 [nf_nat] [ 191.252519] RSP: 0018:ffff8803ef71f750 EFLAGS: 00010246 [ 191.252869] RAX: 0000000000000000 RBX: ffff8803ea188b40 RCX: 1ffff10079b70c00 [ 191.253218] RDX: 1ffff1007d431100 RSI: 0000000000000000 RDI: ffff8803ea188b88 [ 191.253566] RBP: ffff8803cdb86580 R08: ffffed007dee3ef5 R09: ffff8803ef71f770 [ 191.253916] R10: ffffed007dee3ef4 R11: 0000000000000001 R12: 1ffff1007dee3eea [ 191.254267] R13: ffff8803cdb865d8 R14: ffffffff83e2b600 R15: ffff8803ea188bc0 [ 191.254617] FS: 0000000000000000(0000) GS:ffff8803f2380000(0000) knlGS:0000000000000000 [ 191.255232] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 191.255580] CR2: 00007f1f29d55490 CR3: 0000000445a0d005 CR4: 00000000001606e0 [ 191.255928] Call Trace: [ 191.256282] ? __nf_nat_decode_session+0x108/0x108 [nf_nat] [ 191.256634] ? nf_nat_ipv4_fn+0x33d/0x4df [nf_nat_ipv4] [ 191.256980] ? iptable_nat_ipv4_fn+0xc/0xc [iptable_nat] [ 191.257329] nf_nat_ipv4_out+0x235/0x305 [nf_nat_ipv4] [ 191.257674] ? iptable_nat_ipv4_local_fn+0xc/0xc [iptable_nat] [ 191.258020] nf_hook_slow+0xb1/0x11b [ 191.258369] ip_output+0x205/0x243 [ 191.258713] ? ip_mc_output+0x548/0x548 [ 191.259064] ? ip_fragment.constprop.5+0x197/0x197 [ 191.259415] ? iptable_filter_net_init+0x1a/0x1a [iptable_filter] [ 191.259763] ? nf_hook_slow+0xb1/0x11b [ 191.260115] ip_forward+0xe9c/0xecb [ 191.260465] ? ip_forward_finish+0x110/0x110 [ 191.260814] ? ip_frag_mem+0x3d/0x3d [ 191.261158] ? ip_rcv_finish+0xcf8/0xd91 [ 191.261503] ip_rcv+0x985/0xa12 [ 191.261848] ? ip_local_deliver+0x225/0x225 [ 191.262194] ? ip_local_deliver_finish+0x599/0x599 [ 191.262541] ? ip_local_deliver+0x225/0x225 [ 191.262886] __netif_receive_skb_core+0x10ce/0x1c76 [ 191.263236] ? netif_set_xps_queue+0xbdb/0xbdb [ 191.263590] ? enqueue_hrtimer+0xce/0xd8 [ 191.263936] ? hrtimer_start_range_ns+0x827/0x854 [ 191.264286] ? process_backlog+0x1c5/0x3c0 [ 191.264636] process_backlog+0x1c5/0x3c0 [ 191.264979] net_rx_action+0x3aa/0x840 [ 191.265326] ? napi_complete_done+0x22b/0x22b [ 191.265678] ? __qdisc_run+0x36d/0x7c5 [ 191.266029] __do_softirq+0x17f/0x34a [ 191.266378] ? _local_bh_enable+0x1c/0x1c [ 191.266725] run_ksoftirqd+0x11/0x22 [ 191.267075] smpboot_thread_fn+0x50a/0x540 [ 191.267426] ? sort_range+0x17/0x17 [ 191.267773] ? schedule+0x162/0x17a [ 191.268121] kthread+0x2cd/0x2dc [ 191.268468] ? sort_range+0x17/0x17 [ 191.268818] ? kthread_create_on_node+0xa1/0xa1 [ 191.269167] ? __kthread_create_on_node+0x237/0x2d6 [ 191.269515] ret_from_fork+0x1f/0x30 [ 191.269862] Code: e0 07 83 c0 03 38 d0 7c 0c 84 d2 74 08 4c 89 ff e8 65 3f 26 e1 8b 83 80 00 00 00 85 c0 74 0c 8d 50 01 f0 41 0f b1 17 74 04 eb f0 <0f> ff 48 8d 7d 18 48 b8 00 00 00 00 00 fc ff df 48 89 fa 48 c1 [ 191.270787] ---[ end trace 2654a347942730c5 ]--- [ 191.271160] dst_release: dst:00000000c8fe98d6 refcnt:-1