======================================
| [ 1993.068478][ C1] #3: ffffffff9598d560 (rcu_read_lock){....}-{1:3}, at: unwind_next_frame (./include/linux/rcupdate.h:331 ./include/linux/rcupdate.h:841 ./include/linux/rcupdate.h:1155 arch/x86/kernel/unwind_orc.c:479)
| [ 1993.068832][ C1] #4: ffffffff9598d440 (rcu_callback){....}-{0:0}, at: rcu_do_batch (./include/linux/rcupdate.h:331 kernel/rcu/tree.c:2570)
| [ 1993.069178][ C1]
| [ 1993.069178][ C1] stack backtrace:
[ 1993.069434][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[ 1993.069439][ C1] Workqueue: events virtio_fs_requests_done_work
[ 1993.069446][ C1] Call Trace:
[ 1993.069448][ C1]
[ 1993.069451][ C1] dump_stack_lvl (lib/dump_stack.c:123)
[ 1993.069459][ C1] print_usage_bug.part.0 (kernel/locking/lockdep.c:4048)
[ 1993.069464][ C1] mark_lock_irq (kernel/locking/lockdep.c:4013 kernel/locking/lockdep.c:4059 kernel/locking/lockdep.c:4270)
[ 1993.069471][ C1] mark_lock (kernel/locking/lockdep.c:4756)
[ 1993.069475][ C1] mark_usage (kernel/locking/lockdep.c:4645)
[ 1993.069478][ C1] __lock_acquire (kernel/locking/lockdep.c:5194)
[ 1993.069482][ C1] ? unwind_next_frame (./include/linux/rcupdate.h:874 ./include/linux/rcupdate.h:1155 arch/x86/kernel/unwind_orc.c:479)
[ 1993.069485][ C1] ? kasan_save_free_info (mm/kasan/generic.c:579)
[ 1993.069492][ C1] lock_acquire.part.0 (kernel/locking/lockdep.c:473 kernel/locking/lockdep.c:5873)
[ 1993.069495][ C1] ? xa_set_mark (lib/xarray.c:2076 lib/xarray.c:2146)
[ 1993.069499][ C1] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:745)
[ 1993.069505][ C1] ? lock_acquire (./include/trace/events/lock.h:24 kernel/locking/lockdep.c:5834)
[ 1993.069508][ C1] ? xa_set_mark (lib/xarray.c:2076 lib/xarray.c:2146)
[ 1993.069512][ C1] _raw_spin_lock (./include/linux/spinlock_api_smp.h:134 kernel/locking/spinlock.c:154)
[ 1993.069515][ C1] ? xa_set_mark (lib/xarray.c:2076 lib/xarray.c:2146)
[ 1993.069518][ C1] xa_set_mark (lib/xarray.c:2076 lib/xarray.c:2146)
[ 1993.069522][ C1] ? __pfx_xa_set_mark (lib/xarray.c:2144)
[ 1993.069525][ C1] ? __lock_acquire (kernel/locking/lockdep.c:5240)
[ 1993.069531][ C1] ? find_held_lock (kernel/locking/lockdep.c:5353)
[ 1993.069535][ C1] ref_tracker_dir_exit (lib/ref_tracker.c:54 lib/ref_tracker.c:223)
[ 1993.069538][ C1] ? mark_held_locks (kernel/locking/lockdep.c:4328)
[ 1993.069541][ C1] ? _raw_spin_unlock_irqrestore (./include/linux/spinlock_api_smp.h:151 kernel/locking/spinlock.c:194)
[ 1993.069545][ C1] ? __pfx_ref_tracker_dir_exit (lib/ref_tracker.c:213)
[ 1993.069548][ C1] ? ref_tracker_free (lib/ref_tracker.c:281)
[ 1993.069550][ C1] ? __lock_acquire (kernel/locking/lockdep.c:5240)
[ 1993.069553][ C1] ? __pfx_ref_tracker_free (lib/ref_tracker.c:281)
[ 1993.069556][ C1] ? __sk_destruct (./include/linux/instrumented.h:96 ./include/linux/atomic/atomic-instrumented.h:400 ./include/linux/refcount.h:389 ./include/linux/refcount.h:432 ./include/linux/refcount.h:450 ./include/net/net_namespace.h:287 ./include/net/net_namespace.h:390 net/core/sock.c:2368)
[ 1993.069564][ C1] ? rcu_do_batch (kernel/rcu/tree.c:2576)
[ 1993.069566][ C1] ? rcu_core (kernel/rcu/tree.c:2834)
[ 1993.069570][ C1] ? handle_softirqs (kernel/softirq.c:579)
[ 1993.069574][ C1] __put_net (./include/linux/llist.h:238 ./include/linux/llist.h:265 net/core/net_namespace.c:732)
[ 1993.069579][ C1] ? kasan_save_stack (mm/kasan/common.c:48)
[ 1993.069584][ C1] ? __pfx___put_net (net/core/net_namespace.c:729)
[ 1993.069587][ C1] ? bpf_sk_storage_free (./include/linux/rcupdate.h:341 ./include/linux/rcupdate.h:871 net/core/bpf_sk_storage.c:61)
[ 1993.069593][ C1] __sk_destruct (./include/net/net_namespace.h:288 ./include/net/net_namespace.h:390 net/core/sock.c:2368)
[ 1993.069597][ C1] ? rcu_do_batch (kernel/rcu/tree.c:2576)
[ 1993.069600][ C1] rcu_do_batch (kernel/rcu/tree.c:2576)
[ 1993.069604][ C1] ? __pfx_rcu_do_batch (kernel/rcu/tree.c:2500)
[ 1993.069608][ C1] ? mark_held_locks (kernel/locking/lockdep.c:4328)
[ 1993.069611][ C1] ? note_gp_changes (kernel/rcu/tree.c:1326 (discriminator 1))
[ 1993.069614][ C1] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4475)
[ 1993.069619][ C1] rcu_core (kernel/rcu/tree.c:2834)
[ 1993.069623][ C1] handle_softirqs (kernel/softirq.c:579)
[ 1993.069628][ C1] __irq_exit_rcu (kernel/softirq.c:614 kernel/softirq.c:453 kernel/softirq.c:680)
[ 1993.069631][ C1] irq_exit_rcu (kernel/softirq.c:698)
[ 1993.069634][ C1] sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1050 arch/x86/kernel/apic/apic.c:1050)
[ 1993.069638][ C1]
[ 1993.069639][ C1]
[ 1993.069640][ C1] asm_sysvec_apic_timer_interrupt (./arch/x86/include/asm/idtentry.h:702)
[ 1993.069645][ C1] RIP: 0010:__orc_find (arch/x86/kernel/unwind_orc.c:80 arch/x86/kernel/unwind_orc.c:102)
[ 1993.069648][ C1] Code: f2 48 d1 fa 48 8d 5c 95 00 48 89 da 48 c1 ea 03 0f b6 34 0a 48 89 da 83 e2 07 83 c2 03 40 38 f2 7c 05 40 84 f6 75 4b 48 63 13 <48> 01 da 49 39 d5 73 af 4c 8d 63 fc 4c 39 e5 76 b2 4d 29 f7 49 c1
All code
========
0: f2 48 d1 fa repnz sar $1,%rdx
4: 48 8d 5c 95 00 lea 0x0(%rbp,%rdx,4),%rbx
9: 48 89 da mov %rbx,%rdx
c: 48 c1 ea 03 shr $0x3,%rdx
10: 0f b6 34 0a movzbl (%rdx,%rcx,1),%esi
14: 48 89 da mov %rbx,%rdx
17: 83 e2 07 and $0x7,%edx
1a: 83 c2 03 add $0x3,%edx
1d: 40 38 f2 cmp %sil,%dl
20: 7c 05 jl 0x27
22: 40 84 f6 test %sil,%sil
25: 75 4b jne 0x72
27: 48 63 13 movslq (%rbx),%rdx
2a:* 48 01 da add %rbx,%rdx <-- trapping instruction
2d: 49 39 d5 cmp %rdx,%r13
30: 73 af jae 0xffffffffffffffe1
32: 4c 8d 63 fc lea -0x4(%rbx),%r12
36: 4c 39 e5 cmp %r12,%rbp
39: 76 b2 jbe 0xffffffffffffffed
3b: 4d 29 f7 sub %r14,%r15
3e: 49 rex.WB
3f: c1 .byte 0xc1
Code starting with the faulting instruction
===========================================
0: 48 01 da add %rbx,%rdx
3: 49 39 d5 cmp %rdx,%r13
6: 73 af jae 0xffffffffffffffb7
8: 4c 8d 63 fc lea -0x4(%rbx),%r12
c: 4c 39 e5 cmp %r12,%rbp
f: 76 b2 jbe 0xffffffffffffffc3
11: 4d 29 f7 sub %r14,%r15
14: 49 rex.WB
15: c1 .byte 0xc1
[ 1993.069651][ C1] RSP: 0018:ffffc900059df5a8 EFLAGS: 00000246
[ 1993.069654][ C1] RAX: ffffffff96966450 RBX: ffffffff965b4704 RCX: dffffc0000000000
[ 1993.069656][ C1] RDX: fffffffffb91e7aa RSI: 0000000000000000 RDI: ffffffff965b4700
[ 1993.069658][ C1] RBP: ffffffff965b4700 R08: 0000000000000000 R09: ffffc900059df729
[ 1993.069660][ C1] R10: 0000000000000000 R11: ffffffff9598d560 R12: ffffffff965b4708
[ 1993.069661][ C1] R13: ffffffff91ed2ed3 R14: ffffffff965b4700 R15: ffffffff965b4700
[ 1993.069664][ C1] ? kfree (./include/linux/kasan.h:233 mm/slub.c:2381 mm/slub.c:4643 mm/slub.c:4842)
[ 1993.069673][ C1] unwind_next_frame (arch/x86/kernel/unwind_orc.c:495)
[ 1993.069676][ C1] ? kfree (mm/slub.c:4643 mm/slub.c:4842)
[ 1993.069679][ C1] ? __pfx_unwind_next_frame (arch/x86/kernel/unwind_orc.c:469)
[ 1993.069683][ C1] ? __pfx_unwind_next_frame (arch/x86/kernel/unwind_orc.c:469)
[ 1993.069687][ C1] ? kfree (mm/slub.c:4643 mm/slub.c:4842)
[ 1993.069689][ C1] ? kernel_text_address (kernel/extable.c:99)
[ 1993.069694][ C1] ? __pfx_stack_trace_consume_entry (kernel/stacktrace.c:83)
[ 1993.069700][ C1] arch_stack_walk (arch/x86/kernel/stacktrace.c:24)
[ 1993.069705][ C1] ? kfree (mm/slub.c:4643 mm/slub.c:4842)
[ 1993.069709][ C1] stack_trace_save (kernel/stacktrace.c:123)
[ 1993.069712][ C1] ? __pfx_stack_trace_save (kernel/stacktrace.c:114)
[ 1993.069716][ C1] ? validate_chain (kernel/locking/lockdep.c:3804 kernel/locking/lockdep.c:3824 kernel/locking/lockdep.c:3879)
[ 1993.069720][ C1] kasan_save_stack (mm/kasan/common.c:48)
[ 1993.069723][ C1] ? kasan_save_stack (mm/kasan/common.c:48)
[ 1993.069726][ C1] ? kasan_save_track (./arch/x86/include/asm/current.h:25 mm/kasan/common.c:60 mm/kasan/common.c:69)
[ 1993.069729][ C1] ? kasan_save_free_info (mm/kasan/generic.c:579)
[ 1993.069731][ C1] ? __kasan_slab_free (mm/kasan/common.c:271)
[ 1993.069734][ C1] ? kfree (mm/slub.c:4643 mm/slub.c:4842)
[ 1993.069738][ C1] ? find_held_lock (kernel/locking/lockdep.c:5353)
[ 1993.069742][ C1] ? mark_held_locks (kernel/locking/lockdep.c:4328)
[ 1993.069745][ C1] ? _raw_spin_unlock_irqrestore (./include/linux/spinlock_api_smp.h:151 kernel/locking/spinlock.c:194)
[ 1993.069748][ C1] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4475)
[ 1993.069751][ C1] ? _raw_spin_unlock_irqrestore (./arch/x86/include/asm/preempt.h:104 ./include/linux/spinlock_api_smp.h:152 kernel/locking/spinlock.c:194)
[ 1993.069754][ C1] ? __debug_check_no_obj_freed (lib/debugobjects.c:1110)
[ 1993.069760][ C1] ? find_held_lock (kernel/locking/lockdep.c:5353)
[ 1993.069765][ C1] ? __pfx___debug_check_no_obj_freed (lib/debugobjects.c:1070)
[ 1993.069769][ C1] ? mark_held_locks (kernel/locking/lockdep.c:4328)
[ 1993.069772][ C1] ? __call_rcu_common.constprop.0 (kernel/rcu/tree.c:3115)
[ 1993.069776][ C1] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4475)
[ 1993.069779][ C1] kasan_save_track (./arch/x86/include/asm/current.h:25 mm/kasan/common.c:60 mm/kasan/common.c:69)
[ 1993.069782][ C1] kasan_save_free_info (mm/kasan/generic.c:579)
[ 1993.069785][ C1] __kasan_slab_free (mm/kasan/common.c:271)
[ 1993.069788][ C1] kfree (mm/slub.c:4643 mm/slub.c:4842)
[ 1993.069791][ C1] ? detach_buf_split (drivers/virtio/virtio_ring.c:799)
[ 1993.069795][ C1] ? __lock_acquire (kernel/locking/lockdep.c:5240)
[ 1993.069799][ C1] detach_buf_split (drivers/virtio/virtio_ring.c:799)
[ 1993.069804][ C1] virtqueue_get_buf_ctx_split (drivers/virtio/virtio_ring.c:854)
[ 1993.069809][ C1] virtio_fs_requests_done_work (fs/fuse/virtio_fs.c:829)
[ 1993.069814][ C1] ? __pfx_virtio_fs_requests_done_work (fs/fuse/virtio_fs.c:814)
[ 1993.069820][ C1] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:745)
[ 1993.069824][ C1] process_one_work (kernel/workqueue.c:3243)
[ 1993.069830][ C1] ? __pfx_process_one_work (kernel/workqueue.c:3140)
[ 1993.069835][ C1] ? assign_work (kernel/workqueue.c:1200)
[ 1993.069839][ C1] worker_thread (kernel/workqueue.c:3315 kernel/workqueue.c:3402)
[ 1993.069845][ C1] ? __pfx_worker_thread (kernel/workqueue.c:3348)
[ 1993.069848][ C1] kthread (kernel/kthread.c:464)
[ 1993.069852][ C1] ? __pfx_kthread (kernel/kthread.c:413)
[ 1993.069854][ C1] ? ret_from_fork (arch/x86/kernel/process.c:147)
[ 1993.069857][ C1] ? __lock_release (kernel/locking/lockdep.c:5539)
[ 1993.069861][ C1] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:745)
[ 1993.069864][ C1] ? __pfx_kthread (kernel/kthread.c:413)
[ 1993.069867][ C1] ret_from_fork (arch/x86/kernel/process.c:148)
[ 1993.069870][ C1] ? __pfx_kthread (kernel/kthread.c:413)
Finger prints:
mark_lock_irq:mark_lock:mark_usage:__lock_acquire:_raw_spin_lock