Re: INFO: rcu detected stall in skb_free_head
On Sun, Apr 29, 2018 at 6:33 PM, syzbotwrote: > Hello, > > syzbot hit the following crash on upstream commit > a27fc14219f2e3c4a46ba9177b04d9b52c875532 (Mon Apr 16 21:07:39 2018 +) > Merge branch 'parisc-4.17-3' of > git://git.kernel.org/pub/scm/linux/kernel/git/deller/parisc-linux > syzbot dashboard link: > https://syzkaller.appspot.com/bug?extid=cac7c17ec0aca89d3c45 > > Unfortunately, I don't have any reproducer for this crash yet. > Raw console output: > https://syzkaller.appspot.com/x/log.txt?id=6517400396627968 > Kernel config: > https://syzkaller.appspot.com/x/.config?id=-5914490758943236750 > compiler: gcc (GCC) 8.0.1 20180413 (experimental) > > IMPORTANT: if you fix the bug, please add the following tag to the commit: > Reported-by: syzbot+cac7c17ec0aca89d3...@syzkaller.appspotmail.com > It will help syzbot understand when the bug is fixed. See footer for > details. > If you forward the report, please keep this part and the footer. > > INFO: rcu_sched self-detected stall on CPU > 1-...!: (117917 ticks this GP) idle=036/1/4611686018427387906 > softirq=114416/114416 fqs=32 > (t=125000 jiffies g=60712 c=60711 q=345938) > rcu_sched kthread starved for 124847 jiffies! g60712 c60711 f0x2 > RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0 > RCU grace-period kthread stack dump: > rcu_sched R running task23592 9 2 0x8000 > Call Trace: > context_switch kernel/sched/core.c:2848 [inline] > __schedule+0x801/0x1e30 kernel/sched/core.c:3490 > schedule+0xef/0x430 kernel/sched/core.c:3549 > schedule_timeout+0x138/0x240 kernel/time/timer.c:1801 > rcu_gp_kthread+0x6b5/0x1940 kernel/rcu/tree.c:2231 > kthread+0x345/0x410 kernel/kthread.c:238 > ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:412 > NMI backtrace for cpu 1 > CPU: 1 PID: 24 Comm: kworker/1:1 Not tainted 4.17.0-rc1+ #6 > Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS > Google 01/01/2011 > Workqueue: events rht_deferred_worker > Call Trace: > > __dump_stack lib/dump_stack.c:77 [inline] > dump_stack+0x1b9/0x294 lib/dump_stack.c:113 > nmi_cpu_backtrace.cold.4+0x19/0xce lib/nmi_backtrace.c:103 > nmi_trigger_cpumask_backtrace+0x151/0x192 lib/nmi_backtrace.c:62 > arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38 > trigger_single_cpu_backtrace include/linux/nmi.h:156 [inline] > rcu_dump_cpu_stacks+0x175/0x1c2 kernel/rcu/tree.c:1376 > print_cpu_stall kernel/rcu/tree.c:1525 [inline] > check_cpu_stall.isra.61.cold.80+0x36c/0x59a kernel/rcu/tree.c:1593 > __rcu_pending kernel/rcu/tree.c:3356 [inline] > rcu_pending kernel/rcu/tree.c:3401 [inline] > rcu_check_callbacks+0x21b/0xad0 kernel/rcu/tree.c:2763 > update_process_times+0x2d/0x70 kernel/time/timer.c:1636 > tick_sched_handle+0x9f/0x180 kernel/time/tick-sched.c:173 > tick_sched_timer+0x45/0x130 kernel/time/tick-sched.c:1283 > __run_hrtimer kernel/time/hrtimer.c:1386 [inline] > __hrtimer_run_queues+0x3e3/0x10a0 kernel/time/hrtimer.c:1448 > hrtimer_interrupt+0x286/0x650 kernel/time/hrtimer.c:1506 > local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1025 [inline] > smp_apic_timer_interrupt+0x15d/0x710 arch/x86/kernel/apic/apic.c:1050 > apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:863 > RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:783 > [inline] > RIP: 0010:kfree+0x124/0x260 mm/slab.c:3814 > RSP: 0018:8801db105450 EFLAGS: 0286 ORIG_RAX: ff13 > RAX: 0007 RBX: 88006c118040 RCX: 11003b3059e7 > RDX: RSI: 8801d982cf90 RDI: 0286 > RBP: 8801db105470 R08: 8801d982ce78 R09: 0002 > R10: 8801d982c640 R11: R12: 0286 > R13: 8801dac00ac0 R14: 85bd7b69 R15: 88006c0f8180 > skb_free_head+0x99/0xc0 net/core/skbuff.c:550 > skb_release_data+0x690/0x860 net/core/skbuff.c:570 > skb_release_all+0x4a/0x60 net/core/skbuff.c:627 > __kfree_skb net/core/skbuff.c:641 [inline] > kfree_skb+0x195/0x560 net/core/skbuff.c:659 > enqueue_to_backlog+0x2fc/0xc90 net/core/dev.c:3968 > netif_rx_internal+0x14d/0xae0 net/core/dev.c:4181 > netif_rx+0xba/0x400 net/core/dev.c:4206 > loopback_xmit+0x283/0x741 drivers/net/loopback.c:91 > __netdev_start_xmit include/linux/netdevice.h:4087 [inline] > netdev_start_xmit include/linux/netdevice.h:4096 [inline] > xmit_one net/core/dev.c:3053 [inline] > dev_hard_start_xmit+0x264/0xc10 net/core/dev.c:3069 > __dev_queue_xmit+0x2724/0x34c0 net/core/dev.c:3584 > dev_queue_xmit+0x17/0x20 net/core/dev.c:3617 > neigh_hh_output include/net/neighbour.h:472 [inline] > neigh_output include/net/neighbour.h:480 [inline] > ip_finish_output2+0x1046/0x1840 net/ipv4/ip_output.c:229 > ip_finish_output+0x828/0xf80 net/ipv4/ip_output.c:317 > NF_HOOK_COND include/linux/netfilter.h:277 [inline] > ip_output+0x21b/0x850 net/ipv4/ip_output.c:405 > dst_output include/net/dst.h:444
Re: INFO: rcu detected stall in skb_free_head
On Sun, Apr 29, 2018 at 6:33 PM, syzbot wrote: > Hello, > > syzbot hit the following crash on upstream commit > a27fc14219f2e3c4a46ba9177b04d9b52c875532 (Mon Apr 16 21:07:39 2018 +) > Merge branch 'parisc-4.17-3' of > git://git.kernel.org/pub/scm/linux/kernel/git/deller/parisc-linux > syzbot dashboard link: > https://syzkaller.appspot.com/bug?extid=cac7c17ec0aca89d3c45 > > Unfortunately, I don't have any reproducer for this crash yet. > Raw console output: > https://syzkaller.appspot.com/x/log.txt?id=6517400396627968 > Kernel config: > https://syzkaller.appspot.com/x/.config?id=-5914490758943236750 > compiler: gcc (GCC) 8.0.1 20180413 (experimental) > > IMPORTANT: if you fix the bug, please add the following tag to the commit: > Reported-by: syzbot+cac7c17ec0aca89d3...@syzkaller.appspotmail.com > It will help syzbot understand when the bug is fixed. See footer for > details. > If you forward the report, please keep this part and the footer. > > INFO: rcu_sched self-detected stall on CPU > 1-...!: (117917 ticks this GP) idle=036/1/4611686018427387906 > softirq=114416/114416 fqs=32 > (t=125000 jiffies g=60712 c=60711 q=345938) > rcu_sched kthread starved for 124847 jiffies! g60712 c60711 f0x2 > RCU_GP_WAIT_FQS(3) ->state=0x0 ->cpu=0 > RCU grace-period kthread stack dump: > rcu_sched R running task23592 9 2 0x8000 > Call Trace: > context_switch kernel/sched/core.c:2848 [inline] > __schedule+0x801/0x1e30 kernel/sched/core.c:3490 > schedule+0xef/0x430 kernel/sched/core.c:3549 > schedule_timeout+0x138/0x240 kernel/time/timer.c:1801 > rcu_gp_kthread+0x6b5/0x1940 kernel/rcu/tree.c:2231 > kthread+0x345/0x410 kernel/kthread.c:238 > ret_from_fork+0x3a/0x50 arch/x86/entry/entry_64.S:412 > NMI backtrace for cpu 1 > CPU: 1 PID: 24 Comm: kworker/1:1 Not tainted 4.17.0-rc1+ #6 > Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS > Google 01/01/2011 > Workqueue: events rht_deferred_worker > Call Trace: > > __dump_stack lib/dump_stack.c:77 [inline] > dump_stack+0x1b9/0x294 lib/dump_stack.c:113 > nmi_cpu_backtrace.cold.4+0x19/0xce lib/nmi_backtrace.c:103 > nmi_trigger_cpumask_backtrace+0x151/0x192 lib/nmi_backtrace.c:62 > arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38 > trigger_single_cpu_backtrace include/linux/nmi.h:156 [inline] > rcu_dump_cpu_stacks+0x175/0x1c2 kernel/rcu/tree.c:1376 > print_cpu_stall kernel/rcu/tree.c:1525 [inline] > check_cpu_stall.isra.61.cold.80+0x36c/0x59a kernel/rcu/tree.c:1593 > __rcu_pending kernel/rcu/tree.c:3356 [inline] > rcu_pending kernel/rcu/tree.c:3401 [inline] > rcu_check_callbacks+0x21b/0xad0 kernel/rcu/tree.c:2763 > update_process_times+0x2d/0x70 kernel/time/timer.c:1636 > tick_sched_handle+0x9f/0x180 kernel/time/tick-sched.c:173 > tick_sched_timer+0x45/0x130 kernel/time/tick-sched.c:1283 > __run_hrtimer kernel/time/hrtimer.c:1386 [inline] > __hrtimer_run_queues+0x3e3/0x10a0 kernel/time/hrtimer.c:1448 > hrtimer_interrupt+0x286/0x650 kernel/time/hrtimer.c:1506 > local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1025 [inline] > smp_apic_timer_interrupt+0x15d/0x710 arch/x86/kernel/apic/apic.c:1050 > apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:863 > RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:783 > [inline] > RIP: 0010:kfree+0x124/0x260 mm/slab.c:3814 > RSP: 0018:8801db105450 EFLAGS: 0286 ORIG_RAX: ff13 > RAX: 0007 RBX: 88006c118040 RCX: 11003b3059e7 > RDX: RSI: 8801d982cf90 RDI: 0286 > RBP: 8801db105470 R08: 8801d982ce78 R09: 0002 > R10: 8801d982c640 R11: R12: 0286 > R13: 8801dac00ac0 R14: 85bd7b69 R15: 88006c0f8180 > skb_free_head+0x99/0xc0 net/core/skbuff.c:550 > skb_release_data+0x690/0x860 net/core/skbuff.c:570 > skb_release_all+0x4a/0x60 net/core/skbuff.c:627 > __kfree_skb net/core/skbuff.c:641 [inline] > kfree_skb+0x195/0x560 net/core/skbuff.c:659 > enqueue_to_backlog+0x2fc/0xc90 net/core/dev.c:3968 > netif_rx_internal+0x14d/0xae0 net/core/dev.c:4181 > netif_rx+0xba/0x400 net/core/dev.c:4206 > loopback_xmit+0x283/0x741 drivers/net/loopback.c:91 > __netdev_start_xmit include/linux/netdevice.h:4087 [inline] > netdev_start_xmit include/linux/netdevice.h:4096 [inline] > xmit_one net/core/dev.c:3053 [inline] > dev_hard_start_xmit+0x264/0xc10 net/core/dev.c:3069 > __dev_queue_xmit+0x2724/0x34c0 net/core/dev.c:3584 > dev_queue_xmit+0x17/0x20 net/core/dev.c:3617 > neigh_hh_output include/net/neighbour.h:472 [inline] > neigh_output include/net/neighbour.h:480 [inline] > ip_finish_output2+0x1046/0x1840 net/ipv4/ip_output.c:229 > ip_finish_output+0x828/0xf80 net/ipv4/ip_output.c:317 > NF_HOOK_COND include/linux/netfilter.h:277 [inline] > ip_output+0x21b/0x850 net/ipv4/ip_output.c:405 > dst_output include/net/dst.h:444 [inline] > ip_local_out+0xc5/0x1b0