syzbot


INFO: rcu detected stall in mrp_periodic_timer (4)

Status: auto-obsoleted due to no activity on 2023/04/26 22:06
Subsystems: kernel
[Documentation on labels]
First crash: 628d, last: 628d
Similar bugs (7)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-4.14 INFO: rcu detected stall in mrp_periodic_timer 2 1687d 1724d 0/1 auto-closed as invalid on 2020/07/01 22:48
upstream INFO: rcu detected stall in mrp_periodic_timer (2) net 1 1512d 1512d 0/28 auto-closed as invalid on 2020/11/23 04:34
upstream INFO: rcu detected stall in mrp_periodic_timer net 1 1715d 1715d 0/28 auto-closed as invalid on 2020/05/04 02:54
linux-4.19 INFO: rcu detected stall in mrp_periodic_timer 3 1660d 1710d 0/1 auto-closed as invalid on 2020/07/28 00:22
upstream INFO: rcu detected stall in mrp_periodic_timer (3) perf 1 1014d 1014d 0/28 closed as invalid on 2022/02/08 10:10
linux-4.19 BUG: soft lockup in mrp_periodic_timer (2) 3 865d 922d 0/1 auto-obsoleted due to no activity on 2022/10/01 18:22
linux-4.19 BUG: soft lockup in mrp_periodic_timer 1 1052d 1052d 0/1 auto-closed as invalid on 2022/03/28 02:00

Sample crash report:
rcu: INFO: rcu_preempt detected expedited stalls on CPUs/tasks: { 0-.... } 2666 jiffies s: 91397 root: 0x1/.
rcu: blocking rcu_node structures (internal RCU debug):
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 21351 Comm: syz-executor.3 Not tainted 6.2.0-rc5-syzkaller-00047-g7c46948a6e9c #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/12/2023
RIP: 0010:lock_acquire+0x114/0x630 kernel/locking/lockdep.c:5641
Code: 8e e8 d0 69 6b 00 48 8b 54 24 08 48 0f a3 15 73 f1 0e 0d 0f 82 cb 03 00 00 48 c7 c0 ac 2d 73 8e 48 ba 00 00 00 00 00 fc ff df <48> 89 c1 83 e0 07 48 c1 e9 03 83 c0 03 0f b6 14 11 38 d0 7c 08 84
RSP: 0018:ffffc90000007780 EFLAGS: 00000006
RAX: ffffffff8e732dac RBX: 1ffff92000000ef3 RCX: 0000000000000001
RDX: dffffc0000000000 RSI: 0000000000010104 RDI: 0000000000000000
RBP: 0000000000000001 R08: 0000000000000000 R09: ffffffff8e72f897
R10: fffffbfff1ce5f12 R11: 0000000000000001 R12: 0000000000000002
R13: 0000000000000000 R14: ffffffff8c7b9c08 R15: 0000000000000000
FS:  00007fac7916f700(0000) GS:ffff8880b9800000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 000000002006b000 CR3: 0000000184c06000 CR4: 0000000000350ef0
Call Trace:
 <IRQ>
 seqcount_lockdep_reader_access include/linux/seqlock.h:102 [inline]
 timekeeping_get_delta kernel/time/timekeeping.c:254 [inline]
 timekeeping_get_ns kernel/time/timekeeping.c:388 [inline]
 ktime_get+0x14b/0x470 kernel/time/timekeeping.c:848
 clockevents_program_event+0x145/0x370 kernel/time/clockevents.c:326
 tick_program_event+0xb0/0x140 kernel/time/tick-oneshot.c:44
 hrtimer_interrupt+0x585/0x790 kernel/time/hrtimer.c:1868
 local_apic_timer_interrupt arch/x86/kernel/apic/apic.c:1096 [inline]
 __sysvec_apic_timer_interrupt+0x180/0x640 arch/x86/kernel/apic/apic.c:1113
 sysvec_apic_timer_interrupt+0x44/0xc0 arch/x86/kernel/apic/apic.c:1107
 asm_sysvec_apic_timer_interrupt+0x1a/0x20 arch/x86/include/asm/idtentry.h:649
RIP: 0010:__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:152 [inline]
RIP: 0010:_raw_spin_unlock_irqrestore+0x3c/0x70 kernel/locking/spinlock.c:194
Code: 74 24 10 e8 c6 3c 5a f7 48 89 ef e8 6e a9 5a f7 81 e3 00 02 00 00 75 25 9c 58 f6 c4 02 75 2d 48 85 db 74 01 fb bf 01 00 00 00 <e8> 8f 0e 4d f7 65 8b 05 90 ea f9 75 85 c0 74 0a 5b 5d c3 e8 ac 22
RSP: 0018:ffffc90000007ab8 EFLAGS: 00000206
RAX: 0000000000000006 RBX: 0000000000000200 RCX: 1ffffffff22719be
RDX: 0000000000000000 RSI: 0000000000000103 RDI: 0000000000000001
RBP: ffffffff91dee4b8 R08: 0000000000000001 R09: ffffffff91339b9f
R10: 0000000000000001 R11: 0000000000000001 R12: dffffc0000000000
R13: ffffffff8a4eac00 R14: ffff888159e56060 R15: 1ffff92000000f5c
 debug_object_assert_init lib/debugobjects.c:906 [inline]
 debug_object_assert_init+0x24a/0x2e0 lib/debugobjects.c:870
 debug_timer_assert_init kernel/time/timer.c:792 [inline]
 debug_assert_init kernel/time/timer.c:837 [inline]
 __mod_timer+0x9b/0xe80 kernel/time/timer.c:1020
 mrp_periodic_timer_arm net/802/mrp.c:617 [inline]
 mrp_periodic_timer+0x173/0x1f0 net/802/mrp.c:629
 call_timer_fn+0x1da/0x7c0 kernel/time/timer.c:1700
 expire_timers+0x2c6/0x5c0 kernel/time/timer.c:1751
 __run_timers kernel/time/timer.c:2022 [inline]
 __run_timers kernel/time/timer.c:1995 [inline]
 run_timer_softirq+0x326/0x910 kernel/time/timer.c:2035
 __do_softirq+0x1fb/0xadc kernel/softirq.c:571
 invoke_softirq kernel/softirq.c:445 [inline]
 __irq_exit_rcu+0x123/0x180 kernel/softirq.c:650
 irq_exit_rcu+0x9/0x20 kernel/softirq.c:662
 sysvec_apic_timer_interrupt+0x97/0xc0 arch/x86/kernel/apic/apic.c:1107
 </IRQ>
 <TASK>
 asm_sysvec_apic_timer_interrupt+0x1a/0x20 arch/x86/include/asm/idtentry.h:649
RIP: 0010:__raw_spin_unlock_irqrestore include/linux/spinlock_api_smp.h:152 [inline]
RIP: 0010:_raw_spin_unlock_irqrestore+0x3c/0x70 kernel/locking/spinlock.c:194
Code: 74 24 10 e8 c6 3c 5a f7 48 89 ef e8 6e a9 5a f7 81 e3 00 02 00 00 75 25 9c 58 f6 c4 02 75 2d 48 85 db 74 01 fb bf 01 00 00 00 <e8> 8f 0e 4d f7 65 8b 05 90 ea f9 75 85 c0 74 0a 5b 5d c3 e8 ac 22
RSP: 0018:ffffc90016f9fd68 EFLAGS: 00000206
RAX: 0000000000000002 RBX: 0000000000000200 RCX: 1ffffffff1ce5921
RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000001
RBP: ffff88803e474488 R08: 0000000000000001 R09: 0000000000000001
R10: ffffed1007c8e891 R11: 0000000000000001 R12: 0000000000000000
R13: ffffffff8172e280 R14: ffffc90016f9fe68 R15: ffffc90016f9fdb0
 spin_unlock_irqrestore include/linux/spinlock.h:405 [inline]
 unlock_timer kernel/time/posix-timers.c:166 [inline]
 do_timer_settime.part.0+0x168/0x1d0 kernel/time/posix-timers.c:932
 do_timer_settime kernel/time/posix-timers.c:938 [inline]
 __do_sys_timer_settime kernel/time/posix-timers.c:952 [inline]
 __se_sys_timer_settime kernel/time/posix-timers.c:938 [inline]
 __x64_sys_timer_settime+0x21d/0x310 kernel/time/posix-timers.c:938
 do_syscall_x64 arch/x86/entry/common.c:50 [inline]
 do_syscall_64+0x39/0xb0 arch/x86/entry/common.c:80
 entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x7fac7848c0c9
Code: 28 00 00 00 75 05 48 83 c4 28 c3 e8 f1 19 00 00 90 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 b8 ff ff ff f7 d8 64 89 01 48
RSP: 002b:00007fac7916f168 EFLAGS: 00000246 ORIG_RAX: 00000000000000df
RAX: ffffffffffffffda RBX: 00007fac785abf80 RCX: 00007fac7848c0c9
RDX: 000000002006b000 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007fac784e7ae9 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000000
R13: 00007ffeeae2759f R14: 00007fac7916f300 R15: 0000000000022000
 </TASK>
usb 4-1: USB disconnect, device number 12
usb 5-1: new high-speed USB device number 15 using dummy_hcd
usb 5-1: Using ep0 maxpacket: 16
usb 5-1: config 1 contains an unexpected descriptor of type 0x1, skipping
usb 5-1: config 1 has an invalid descriptor of length 1, skipping remainder of the config
usb 5-1: config 1 has 1 interface, different from the descriptor's value: 3
usb 5-1: New USB device found, idVendor=1d6b, idProduct=0101, bcdDevice= 0.40
usb 5-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
usb 5-1: Product: syz
usb 5-1: Manufacturer: syz
usb 5-1: SerialNumber: syz
usb 5-1: 0:2 : does not exist
usb 5-1: USB disconnect, device number 15
usb 5-1: new high-speed USB device number 16 using dummy_hcd
usb 5-1: Using ep0 maxpacket: 16

Crashes (1):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2023/01/26 21:57 upstream 7c46948a6e9c 9dfcf09c .config console log report info [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce-root INFO: rcu detected stall in mrp_periodic_timer
* Struck through repros no longer work on HEAD.