INFO: rcu detected stall in dev_ioctl (3)

7 views
Skip to first unread message

syzbot

unread,
Dec 30, 2021, 10:27:22 AM12/30/21
to syzkaller...@googlegroups.com
Hello,

syzbot found the following issue on:

HEAD commit: 3f8a27f9e27b Linux 4.19.211
git tree: linux-4.19.y
console output: https://syzkaller.appspot.com/x/log.txt?x=125fabebb00000
kernel config: https://syzkaller.appspot.com/x/.config?x=9b9277b418617afe
dashboard link: https://syzkaller.appspot.com/bug?extid=3aad3a16b16a0391c2ad
compiler: gcc version 10.2.1 20210110 (Debian 10.2.1-6)

Unfortunately, I don't have any reproducer for this issue yet.

IMPORTANT: if you fix the issue, please add the following tag to the commit:
Reported-by: syzbot+3aad3a...@syzkaller.appspotmail.com

batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
rcu: INFO: rcu_preempt self-detected stall on CPU
rcu: 0-....: (1 GPs behind) idle=836/1/0x4000000000000002 softirq=340528/340529 fqs=5234
rcu: (t=10502 jiffies g=522989 q=7848)
NMI backtrace for cpu 0
CPU: 0 PID: 4766 Comm: syz-executor.3 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
<IRQ>
__dump_stack lib/dump_stack.c:77 [inline]
dump_stack+0x1fc/0x2ef lib/dump_stack.c:118
nmi_cpu_backtrace.cold+0x63/0xa2 lib/nmi_backtrace.c:101
nmi_trigger_cpumask_backtrace+0x1a6/0x1f0 lib/nmi_backtrace.c:62
trigger_single_cpu_backtrace include/linux/nmi.h:164 [inline]
rcu_dump_cpu_stacks+0x15f/0x19c kernel/rcu/tree.c:1340
print_cpu_stall kernel/rcu/tree.c:1478 [inline]
check_cpu_stall kernel/rcu/tree.c:1550 [inline]
__rcu_pending kernel/rcu/tree.c:3293 [inline]
rcu_pending kernel/rcu/tree.c:3336 [inline]
rcu_check_callbacks.cold+0x62d/0xe19 kernel/rcu/tree.c:2682
update_process_times+0x2a/0x70 kernel/time/timer.c:1650
tick_sched_handle+0x9b/0x180 kernel/time/tick-sched.c:168
tick_sched_timer+0xfc/0x290 kernel/time/tick-sched.c:1278
__run_hrtimer kernel/time/hrtimer.c:1465 [inline]
__hrtimer_run_queues+0x3f6/0xe60 kernel/time/hrtimer.c:1527
hrtimer_interrupt+0x326/0x9e0 kernel/time/hrtimer.c:1585
local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1071 [inline]
smp_apic_timer_interrupt+0x10c/0x550 arch/x86/kernel/apic/apic.c:1096
apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:894
</IRQ>
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:789 [inline]
RIP: 0010:lock_acquire+0x1ec/0x3c0 kernel/locking/lockdep.c:3911
Code: 08 00 00 00 00 00 00 48 c1 e8 03 80 3c 10 00 0f 85 b7 01 00 00 48 83 3d 09 2e a6 08 00 0f 84 2a 01 00 00 48 8b 7c 24 08 57 9d <0f> 1f 44 00 00 48 83 c4 18 5b 5d 41 5c 41 5d 41 5e 41 5f c3 65 8b
RSP: 0018:ffff88801184f6a8 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13
RAX: 1ffffffff13e3051 RBX: ffff88804e9ee380 RCX: 00000000000071d9
RDX: dffffc0000000000 RSI: 0000000009b92eb4 RDI: 0000000000000286
RBP: ffff88801b88c010 R08: ffffffff8cd50408 R09: 0000000000000002
R10: ffff88804e9eec58 R11: 0000000000000000 R12: 0000000000000000
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000001
__raw_spin_lock_bh include/linux/spinlock_api_smp.h:135 [inline]
_raw_spin_lock_bh+0x2f/0x40 kernel/locking/spinlock.c:168
spin_lock_bh include/linux/spinlock.h:334 [inline]
batadv_tt_local_purge_pending_clients+0x112/0x3a0 net/batman-adv/translation-table.c:3905
batadv_tt_local_resize_to_mtu+0x96/0x130 net/batman-adv/translation-table.c:4208
batadv_update_min_mtu net/batman-adv/hard-interface.c:637 [inline]
batadv_hard_if_event+0xbb3/0x15c0 net/batman-adv/hard-interface.c:1059
notifier_call_chain+0xc0/0x230 kernel/notifier.c:93
call_netdevice_notifiers_mtu net/core/dev.c:1785 [inline]
dev_set_mtu_ext+0x384/0x580 net/core/dev.c:7819
dev_set_mtu+0x95/0x120 net/core/dev.c:7840
dev_ifsioc+0x30a/0x8c0 net/core/dev_ioctl.c:244
dev_ioctl+0x1ab/0xc50 net/core/dev_ioctl.c:488
sock_do_ioctl+0x178/0x300 net/socket.c:1038
sock_ioctl+0x2ef/0x5d0 net/socket.c:1135
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:501 [inline]
do_vfs_ioctl+0xcdb/0x12e0 fs/ioctl.c:688
ksys_ioctl+0x9b/0xc0 fs/ioctl.c:705
__do_sys_ioctl fs/ioctl.c:712 [inline]
__se_sys_ioctl fs/ioctl.c:710 [inline]
__x64_sys_ioctl+0x6f/0xb0 fs/ioctl.c:710
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fcdf18a8e99
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007fcdf021e168 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007fcdf19bbf60 RCX: 00007fcdf18a8e99
RDX: 0000000020000040 RSI: 0000000000008922 RDI: 0000000000000004
RBP: 00007fcdf1902ff1 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fffdd82fbbf R14: 00007fcdf021e300 R15: 0000000000022000
net_ratelimit: 5449 callbacks suppressed
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
rcu: INFO: rcu_sched detected stalls on CPUs/tasks:
rcu: 0-....: (10544 ticks this GP) idle=836/1/0x4000000000000000 softirq=340500/340529 fqs=5212
rcu: (detected by 1, t=10502 jiffies, g=12461, q=5)
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 4766 Comm: syz-executor.3 Not tainted 4.19.211-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:arch_local_irq_restore arch/x86/include/asm/paravirt.h:789 [inline]
RIP: 0010:lock_release+0x429/0x8b0 kernel/locking/lockdep.c:3930
Code: 84 08 00 00 00 00 00 00 48 c1 e8 03 80 3c 10 00 0f 85 95 03 00 00 48 83 3d 7b 34 a6 08 00 0f 84 cc 01 00 00 48 8b 3c 24 57 9d <0f> 1f 44 00 00 48 b8 00 00 00 00 00 fc ff df 49 c7 04 04 00 00 00
RSP: 0018:ffff88801184f650 EFLAGS: 00000286
RAX: 1ffffffff13e3051 RBX: ffff88804e9ee380 RCX: 1ffff11009d3dd8b
RDX: dffffc0000000000 RSI: 0000000000000003 RDI: 0000000000000286
RBP: 0000000000000002 R08: 0000000000000001 R09: 0000000000000002
R10: 0000000000000000 R11: 0000000000000000 R12: 1ffff11002309ecd
R13: 6e6b014b03b0a3b9 R14: ffff88804e9ee380 R15: 0000000000000003
FS: 00007fcdf021e700(0000) GS:ffff8880ba000000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000000000 CR3: 000000009ac5f000 CR4: 00000000003406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
__raw_spin_unlock_bh include/linux/spinlock_api_smp.h:174 [inline]
_raw_spin_unlock_bh+0x17/0x30 kernel/locking/spinlock.c:200
spin_unlock_bh include/linux/spinlock.h:374 [inline]
batadv_tt_local_purge_pending_clients+0x2aa/0x3a0 net/batman-adv/translation-table.c:3924
batadv_tt_local_resize_to_mtu+0x96/0x130 net/batman-adv/translation-table.c:4208
batadv_update_min_mtu net/batman-adv/hard-interface.c:637 [inline]
batadv_hard_if_event+0xbb3/0x15c0 net/batman-adv/hard-interface.c:1059
notifier_call_chain+0xc0/0x230 kernel/notifier.c:93
call_netdevice_notifiers_mtu net/core/dev.c:1785 [inline]
dev_set_mtu_ext+0x384/0x580 net/core/dev.c:7819
dev_set_mtu+0x95/0x120 net/core/dev.c:7840
dev_ifsioc+0x30a/0x8c0 net/core/dev_ioctl.c:244
dev_ioctl+0x1ab/0xc50 net/core/dev_ioctl.c:488
sock_do_ioctl+0x178/0x300 net/socket.c:1038
sock_ioctl+0x2ef/0x5d0 net/socket.c:1135
vfs_ioctl fs/ioctl.c:46 [inline]
file_ioctl fs/ioctl.c:501 [inline]
do_vfs_ioctl+0xcdb/0x12e0 fs/ioctl.c:688
ksys_ioctl+0x9b/0xc0 fs/ioctl.c:705
__do_sys_ioctl fs/ioctl.c:712 [inline]
__se_sys_ioctl fs/ioctl.c:710 [inline]
__x64_sys_ioctl+0x6f/0xb0 fs/ioctl.c:710
do_syscall_64+0xf9/0x620 arch/x86/entry/common.c:293
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fcdf18a8e99
Code: ff ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 40 00 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 c7 c1 bc ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007fcdf021e168 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 00007fcdf19bbf60 RCX: 00007fcdf18a8e99
RDX: 0000000020000040 RSI: 0000000000008922 RDI: 0000000000000004
RBP: 00007fcdf1902ff1 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007fffdd82fbbf R14: 00007fcdf021e300 R15: 0000000000022000
rcu: INFO: rcu_sched detected expedited stalls on CPUs/tasks: { 0-... } 10620 jiffies s: 4665 root: 0x1/.
rcu: blocking rcu_node structures:
Task dump for CPU 0:
syz-executor.3 R running task 26512 4766 18697 0x8000000e
Call Trace:
net_ratelimit: 5022 callbacks suppressed
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
batman_adv: batadv0: Forced to purge local tt entries to fit new maximum fragment MTU (-320)
----------------
Code disassembly (best guess):
0: 08 00 or %al,(%rax)
2: 00 00 add %al,(%rax)
4: 00 00 add %al,(%rax)
6: 00 48 c1 add %cl,-0x3f(%rax)
9: e8 03 80 3c 10 callq 0x103c8011
e: 00 0f add %cl,(%rdi)
10: 85 b7 01 00 00 48 test %esi,0x48000001(%rdi)
16: 83 3d 09 2e a6 08 00 cmpl $0x0,0x8a62e09(%rip) # 0x8a62e26
1d: 0f 84 2a 01 00 00 je 0x14d
23: 48 8b 7c 24 08 mov 0x8(%rsp),%rdi
28: 57 push %rdi
29: 9d popfq
* 2a: 0f 1f 44 00 00 nopl 0x0(%rax,%rax,1) <-- trapping instruction
2f: 48 83 c4 18 add $0x18,%rsp
33: 5b pop %rbx
34: 5d pop %rbp
35: 41 5c pop %r12
37: 41 5d pop %r13
39: 41 5e pop %r14
3b: 41 5f pop %r15
3d: c3 retq
3e: 65 gs
3f: 8b .byte 0x8b


---
This report is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.

syzbot will keep track of this issue. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.

syzbot

unread,
Apr 29, 2022, 11:27:14 AM4/29/22
to syzkaller...@googlegroups.com
Auto-closing this bug as obsolete.
Crashes did not happen for a while, no reproducer and no activity.
Reply all
Reply to author
Forward
0 new messages