====================================== | [ 44.381203][ C1] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:129) | [ 44.381399][ C1] | [ 44.381479][ C1] | [ 44.381479][ C1] stack backtrace: [ 44.382030][ C1] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014 [ 44.382385][ C1] Call Trace: [ 44.382508][ C1] <IRQ> [ 44.382592][ C1] dump_stack_lvl (lib/dump_stack.c:117) [ 44.382861][ C1] print_irq_inversion_bug.part.0 (kernel/locking/lockdep.c:4024) [ 44.383061][ C1] ? __pfx_print_irq_inversion_bug.part.0 (kernel/locking/lockdep.c:4024) [ 44.383264][ C1] ? __pfx_usage_skip (kernel/locking/lockdep.c:2264) [ 44.383425][ C1] ? __pfx_usage_match (kernel/locking/lockdep.c:2256) [ 44.383687][ C1] ? arch_stack_walk (arch/x86/kernel/stacktrace.c:26) [ 44.383849][ C1] mark_lock_irq (kernel/locking/lockdep.c:4244) [ 44.384023][ C1] ? __pfx_mark_lock_irq (kernel/locking/lockdep.c:4207) [ 44.384184][ C1] ? stack_trace_save (kernel/stacktrace.c:123) [ 44.384460][ C1] ? add_chain_cache (kernel/locking/lockdep.c:3730) [ 44.384624][ C1] ? save_trace (kernel/locking/lockdep.c:586) [ 44.384785][ C1] mark_lock (kernel/locking/lockdep.c:4678) [ 44.384914][ C1] mark_usage (kernel/locking/lockdep.c:4567) [ 44.385035][ C1] __lock_acquire (kernel/locking/lockdep.c:5091) [ 44.385305][ C1] ? __lock_acquire (kernel/locking/lockdep.c:5137) [ 44.385465][ C1] ? __pfx_br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:80) bridge [ 44.385743][ C1] lock_acquire.part.0 (kernel/locking/lockdep.c:467 kernel/locking/lockdep.c:5756) [ 44.385908][ C1] ? br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:87) bridge [ 44.386250][ C1] ? __pfx_lock_acquire.part.0 (kernel/locking/lockdep.c:5719) [ 44.386411][ C1] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 52)) [ 44.386578][ C1] ? br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:87) bridge [ 44.386927][ C1] ? lock_acquire (kernel/locking/lockdep.c:5727) [ 44.387093][ C1] ? br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:87) bridge [ 44.387329][ C1] _raw_spin_lock (./include/linux/spinlock_api_smp.h:134 kernel/locking/spinlock.c:154) [ 44.387489][ C1] ? br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:87) bridge [ 44.387727][ C1] br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:87) bridge [ 44.387982][ C1] ? __pfx_br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:80) bridge [ 44.388265][ C1] call_timer_fn (kernel/time/timer.c:1793) [ 44.388430][ C1] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1783) [ 44.388588][ C1] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1783) [ 44.388748][ C1] ? __pfx_call_timer_fn (kernel/time/timer.c:1770) [ 44.388907][ C1] ? hlock_class (./arch/x86/include/asm/bitops.h:227 ./arch/x86/include/asm/bitops.h:239 ./include/asm-generic/bitops/instrumented-non-atomic.h:142 kernel/locking/lockdep.c:228) [ 44.389068][ C1] ? mark_held_locks (kernel/locking/lockdep.c:4274) [ 44.389233][ C1] __run_timers (kernel/time/timer.c:1845 kernel/time/timer.c:2418) [ 44.389393][ C1] ? __pfx_br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:80) bridge [ 44.389674][ C1] ? __pfx___run_timers (kernel/time/timer.c:2389) [ 44.389836][ C1] ? do_raw_spin_lock (./arch/x86/include/asm/atomic.h:115 ./include/linux/atomic/atomic-arch-fallback.h:2170 ./include/linux/atomic/atomic-instrumented.h:1302 ./include/asm-generic/qspinlock.h:111 kernel/locking/spinlock_debug.c:116) [ 44.390113][ C1] ? __pfx_do_raw_spin_lock (kernel/locking/spinlock_debug.c:114) [ 44.390273][ C1] ? lock_acquire (kernel/locking/lockdep.c:5727) [ 44.390434][ C1] ? run_timer_softirq (kernel/time/timer.c:2429 kernel/time/timer.c:2422 kernel/time/timer.c:2438 kernel/time/timer.c:2448) [ 44.390597][ C1] run_timer_softirq (kernel/time/timer.c:2430 kernel/time/timer.c:2422 kernel/time/timer.c:2438 kernel/time/timer.c:2448) [ 44.390757][ C1] __do_softirq (kernel/softirq.c:554) [ 44.390917][ C1] irq_exit_rcu (kernel/softirq.c:428 kernel/softirq.c:633 kernel/softirq.c:645) [ 44.391041][ C1] sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1043 arch/x86/kernel/apic/apic.c:1043) [ 44.391201][ C1] </IRQ> [ 44.391284][ C1] <TASK> [ 44.391367][ C1] asm_sysvec_apic_timer_interrupt (./arch/x86/include/asm/idtentry.h:702) [ 44.391567][ C1] RIP: 0010:_raw_spin_unlock_irqrestore (./include/linux/spinlock_api_smp.h:152 kernel/locking/spinlock.c:194) [ 44.391774][ C1] Code: 10 e8 21 49 9b fd 48 89 ef e8 59 b9 9b fd 81 e3 00 02 00 00 75 1d 9c 58 f6 c4 02 75 29 48 85 db 74 01 fb 65 ff 0d 05 bf 8f 75 <74> 0e 5b 5d c3 cc cc cc cc e8 4f b3 be fd eb dc 0f 1f 44 00 00 5b All code ======== 0: 10 e8 adc %ch,%al 2: 21 49 9b and %ecx,-0x65(%rcx) 5: fd std 6: 48 89 ef mov %rbp,%rdi 9: e8 59 b9 9b fd call 0xfffffffffd9bb967 e: 81 e3 00 02 00 00 and $0x200,%ebx 14: 75 1d jne 0x33 16: 9c pushf 17: 58 pop %rax 18: f6 c4 02 test $0x2,%ah 1b: 75 29 jne 0x46 1d: 48 85 db test %rbx,%rbx 20: 74 01 je 0x23 22: fb sti 23: 65 ff 0d 05 bf 8f 75 decl %gs:0x758fbf05(%rip) # 0x758fbf2f 2a:* 74 0e je 0x3a <-- trapping instruction 2c: 5b pop %rbx 2d: 5d pop %rbp 2e: c3 ret 2f: cc int3 30: cc int3 31: cc int3 32: cc int3 33: e8 4f b3 be fd call 0xfffffffffdbeb387 38: eb dc jmp 0x16 3a: 0f 1f 44 00 00 nopl 0x0(%rax,%rax,1) 3f: 5b pop %rbx Code starting with the faulting instruction =========================================== 0: 74 0e je 0x10 2: 5b pop %rbx 3: 5d pop %rbp 4: c3 ret 5: cc int3 6: cc int3 7: cc int3 8: cc int3 9: e8 4f b3 be fd call 0xfffffffffdbeb35d e: eb dc jmp 0xffffffffffffffec 10: 0f 1f 44 00 00 nopl 0x0(%rax,%rax,1) 15: 5b pop %rbx [ 44.392465][ C1] RSP: 0018:ffffc9000086f1c0 EFLAGS: 00000282 [ 44.392667][ C1] RAX: 0000000000000002 RBX: 0000000000000200 RCX: 1ffffffff1b27f20 [ 44.393037][ C1] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffffff8a740911 [ 44.393273][ C1] RBP: ffff888001041080 R08: 0000000000000001 R09: fffffbfff1b238af [ 44.393511][ C1] R10: ffffffff8d91c57f R11: 0000000000001000 R12: ffff888001041080 [ 44.393754][ C1] R13: ffffea000013b600 R14: ffff8880010433c0 R15: ffff888004edc000 [ 44.393995][ C1] ? _raw_spin_unlock_irqrestore (./include/linux/spinlock_api_smp.h:151 kernel/locking/spinlock.c:194) [ 44.394197][ C1] get_partial_node.part.0 (mm/slub.c:2623) [ 44.394376][ C1] ___slab_alloc (mm/slub.c:2583 mm/slub.c:2701 mm/slub.c:3505) [ 44.394534][ C1] ? fs_reclaim_acquire (mm/page_alloc.c:3715 mm/page_alloc.c:3706) [ 44.394694][ C1] ? p9_fcall_init (net/9p/client.c:232) [ 44.394855][ C1] ? __pfx___lock_release (kernel/locking/lockdep.c:5406) [ 44.395022][ C1] ? p9_fcall_init (net/9p/client.c:232) [ 44.395182][ C1] ? __kmalloc (mm/slub.c:3610 mm/slub.c:3663 mm/slub.c:3835 mm/slub.c:3965 mm/slub.c:3979) [ 44.395341][ C1] __kmalloc (mm/slub.c:3610 mm/slub.c:3663 mm/slub.c:3835 mm/slub.c:3965 mm/slub.c:3979) [ 44.395462][ C1] p9_fcall_init (net/9p/client.c:232) [ 44.395626][ C1] p9_tag_alloc (net/9p/client.c:295) [ 44.395893][ C1] ? __pfx_p9_tag_alloc (net/9p/client.c:277) [ 44.396064][ C1] ? netfs_alloc_subrequest (./include/linux/slab.h:632 ./include/linux/slab.h:749 fs/netfs/objects.c:143) [ 44.396227][ C1] ? is_bpf_text_address (kernel/bpf/core.c:772) [ 44.396399][ C1] p9_client_prepare_req (net/9p/client.c:641) [ 44.396662][ C1] ? hlock_class (./arch/x86/include/asm/bitops.h:227 ./arch/x86/include/asm/bitops.h:239 ./include/asm-generic/bitops/instrumented-non-atomic.h:142 kernel/locking/lockdep.c:228) [ 44.396822][ C1] ? __pfx_p9_client_prepare_req (net/9p/client.c:625) [ 44.397022][ C1] ? __pfx_validate_chain (kernel/locking/lockdep.c:3825) [ 44.397183][ C1] p9_client_rpc (net/9p/client.c:688 (discriminator 4)) [ 44.397450][ C1] ? __pfx_p9_client_rpc (net/9p/client.c:672) [ 44.397615][ C1] ? __lock_release (kernel/locking/lockdep.c:5430) [ 44.397775][ C1] ? __debug_object_init (lib/debugobjects.c:622) [ 44.397938][ C1] ? __pfx___lock_release (kernel/locking/lockdep.c:5406) [ 44.398104][ C1] p9_client_read_once (net/9p/client.c:1562) [ 44.398366][ C1] ? mark_held_locks (kernel/locking/lockdep.c:4274) [ 44.398525][ C1] ? __pfx_p9_client_read_once (net/9p/client.c:1534) [ 44.398683][ C1] ? lockdep_hardirqs_on_prepare.part.0 (kernel/locking/lockdep.c:4300 kernel/locking/lockdep.c:4359) [ 44.398882][ C1] ? __debug_object_init (lib/debugobjects.c:622) [ 44.399147][ C1] p9_client_read (net/9p/client.c:1522) [ 44.399314][ C1] v9fs_issue_read (fs/9p/vfs_addr.c:76) [ 44.399480][ C1] ? __pfx_v9fs_issue_read (fs/9p/vfs_addr.c:66) [ 44.399676][ C1] ? netfs_rreq_prepare_read (fs/netfs/io.c:640) [ 44.399939][ C1] netfs_rreq_submit_slice (fs/netfs/io.c:694) [ 44.400128][ C1] netfs_begin_read (fs/netfs/io.c:741) [ 44.400290][ C1] ? netfs_extract_user_iter (fs/netfs/iterator.c:39) [ 44.400450][ C1] ? __pfx_netfs_begin_read (fs/netfs/io.c:708) [ 44.400612][ C1] ? __pfx_do_raw_spin_lock (kernel/locking/spinlock_debug.c:114) [ 44.400780][ C1] ? __pfx_netfs_extract_user_iter (fs/netfs/iterator.c:39) [ 44.400978][ C1] ? netfs_alloc_request (fs/netfs/objects.c:64) [ 44.401138][ C1] ? do_raw_spin_unlock (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141) [ 44.401298][ C1] ? _raw_spin_unlock (./arch/x86/include/asm/preempt.h:94 ./include/linux/spinlock_api_smp.h:143 kernel/locking/spinlock.c:186) [ 44.401458][ C1] ? netfs_alloc_request (fs/netfs/objects.c:64) [ 44.401619][ C1] netfs_unbuffered_read_iter_locked (fs/netfs/direct_read.c:84) [ 44.401820][ C1] netfs_unbuffered_read_iter (fs/netfs/direct_read.c:121) [ 44.402092][ C1] do_iter_readv_writev (./include/linux/fs.h:2102 fs/read_write.c:739) [ 44.402254][ C1] ? __pfx_do_iter_readv_writev (fs/read_write.c:728) [ 44.402416][ C1] ? kasan_save_stack (mm/kasan/common.c:49) [ 44.402577][ C1] ? entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:129) [ 44.402878][ C1] ? __pfx___lock_release (kernel/locking/lockdep.c:5406) [ 44.403041][ C1] vfs_iter_read (fs/read_write.c:830) [ 44.403204][ C1] backing_file_read_iter (fs/backing-file.c:160) [ 44.403374][ C1] ovl_read_iter (./include/linux/file.h:47 fs/overlayfs/file.c:283) [ 44.403742][ C1] ? __pfx_ovl_read_iter (fs/overlayfs/file.c:264) [ 44.403904][ C1] ? __pfx___debug_check_no_obj_freed (lib/debugobjects.c:960) [ 44.404113][ C1] ? __pfx_ovl_file_accessed (fs/overlayfs/file.c:236) [ 44.404284][ C1] vfs_read (./include/linux/fs.h:2102 fs/read_write.c:395 fs/read_write.c:476) [ 44.404410][ C1] ? kmem_cache_free (mm/slub.c:4280 mm/slub.c:4344) [ 44.404674][ C1] ? __pfx_vfs_read (fs/read_write.c:457) [ 44.404835][ C1] ? __pfx_do_sys_openat2 (fs/open.c:1392) [ 44.405002][ C1] ? __virt_addr_valid (./arch/x86/include/asm/preempt.h:94 ./include/linux/rcupdate.h:896 ./include/linux/mmzone.h:2029 arch/x86/mm/physaddr.c:65) [ 44.405164][ C1] ? __fget_light (./include/linux/atomic/atomic-arch-fallback.h:479 ./include/linux/atomic/atomic-instrumented.h:50 fs/file.c:1145) [ 44.405431][ C1] ksys_read (fs/read_write.c:619) [ 44.405553][ C1] ? __pfx_ksys_read (fs/read_write.c:609) [ 44.405718][ C1] do_syscall_64 (arch/x86/entry/common.c:52 arch/x86/entry/common.c:83) [ 44.405880][ C1] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:129) [ 44.406182][ C1] RIP: 0033:0x7fb3e7776138 [ 44.406349][ C1] Code: c0 48 8d 44 24 d0 48 89 44 24 c8 eb bb 0f 1f 44 00 00 f7 d8 89 05 b8 f0 00 00 b8 ff ff ff ff c3 66 90 f3 0f 1e fa 31 c0 0f 05 <48> 3d 00 f0 ff ff 77 08 c3 0f 1f 80 00 00 00 00 f7 d8 89 05 90 f0 All code ======== 0: c0 48 8d 44 rorb $0x44,-0x73(%rax) 4: 24 d0 and $0xd0,%al 6: 48 89 44 24 c8 mov %rax,-0x38(%rsp) b: eb bb jmp 0xffffffffffffffc8 d: 0f 1f 44 00 00 nopl 0x0(%rax,%rax,1) 12: f7 d8 neg %eax 14: 89 05 b8 f0 00 00 mov %eax,0xf0b8(%rip) # 0xf0d2 1a: b8 ff ff ff ff mov $0xffffffff,%eax 1f: c3 ret 20: 66 90 xchg %ax,%ax 22: f3 0f 1e fa endbr64 26: 31 c0 xor %eax,%eax 28: 0f 05 syscall 2a:* 48 3d 00 f0 ff ff cmp $0xfffffffffffff000,%rax <-- trapping instruction 30: 77 08 ja 0x3a 32: c3 ret 33: 0f 1f 80 00 00 00 00 nopl 0x0(%rax) 3a: f7 d8 neg %eax 3c: 89 .byte 0x89 3d: 05 .byte 0x5 3e: 90 nop 3f: f0 lock Code starting with the faulting instruction =========================================== 0: 48 3d 00 f0 ff ff cmp $0xfffffffffffff000,%rax 6: 77 08 ja 0x10 8: c3 ret 9: 0f 1f 80 00 00 00 00 nopl 0x0(%rax) 10: f7 d8 neg %eax 12: 89 .byte 0x89 13: 05 .byte 0x5 14: 90 nop 15: f0 lock [ 44.407086][ C1] RSP: 002b:00007ffff3f0fef8 EFLAGS: 00000246 ORIG_RAX: 0000000000000000 [ 44.407333][ C1] RAX: ffffffffffffffda RBX: 00007ffff3f1017f RCX: 00007fb3e7776138 [ 44.407570][ C1] RDX: 0000000000000340 RSI: 00007ffff3f10198 RDI: 0000000000000005 [ 44.407809][ C1] RBP: 00007ffff3f0ff60 R08: 0000000000080000 R09: 00007ffff3f0ff70 [ 44.408064][ C1] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000340 Finger prints: dump_stack_lvl:mark_lock_irq:mark_lock:mark_usage