======================================
| [ 42.842431][ C1] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:129)
| [ 42.842644][ C1]
| [ 42.842729][ C1]
| [ 42.842729][ C1] stack backtrace:
[ 42.843198][ C1] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[ 42.843579][ C1] Call Trace:
[ 42.843834][ C1]
[ 42.843924][ C1] dump_stack_lvl (lib/dump_stack.c:117)
[ 42.844098][ C1] print_irq_inversion_bug.part.0 (kernel/locking/lockdep.c:4024)
[ 42.844310][ C1] ? __pfx_print_irq_inversion_bug.part.0 (kernel/locking/lockdep.c:4024)
[ 42.844530][ C1] ? __pfx_usage_skip (kernel/locking/lockdep.c:2264)
[ 42.844703][ C1] ? __pfx_usage_match (kernel/locking/lockdep.c:2256)
[ 42.844873][ C1] ? arch_stack_walk (arch/x86/kernel/stacktrace.c:26)
[ 42.845045][ C1] mark_lock_irq (kernel/locking/lockdep.c:4244)
[ 42.845216][ C1] ? __pfx_mark_lock_irq (kernel/locking/lockdep.c:4207)
[ 42.845386][ C1] ? stack_trace_save (kernel/stacktrace.c:123)
[ 42.845557][ C1] ? add_chain_cache (kernel/locking/lockdep.c:3730)
[ 42.845727][ C1] ? save_trace (kernel/locking/lockdep.c:586)
[ 42.845901][ C1] mark_lock (kernel/locking/lockdep.c:4678)
[ 42.846035][ C1] mark_usage (kernel/locking/lockdep.c:4567)
[ 42.846164][ C1] __lock_acquire (kernel/locking/lockdep.c:5091)
[ 42.846337][ C1] ? __lock_acquire (kernel/locking/lockdep.c:5137)
[ 42.846508][ C1] ? __pfx_br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:80)
[ 42.846723][ C1] lock_acquire.part.0 (kernel/locking/lockdep.c:467 kernel/locking/lockdep.c:5756)
[ 42.846996][ C1] ? br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:87)
[ 42.847208][ C1] ? __pfx_lock_acquire.part.0 (kernel/locking/lockdep.c:5719)
[ 42.847378][ C1] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 52))
[ 42.847551][ C1] ? br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:87)
[ 42.847760][ C1] ? lock_acquire (kernel/locking/lockdep.c:5727)
[ 42.847931][ C1] ? br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:87)
[ 42.848142][ C1] _raw_spin_lock (./include/linux/spinlock_api_smp.h:134 kernel/locking/spinlock.c:154)
[ 42.848416][ C1] ? br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:87)
[ 42.848627][ C1] br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:87)
[ 42.848839][ C1] ? __pfx_br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:80)
[ 42.849053][ C1] call_timer_fn (kernel/time/timer.c:1793)
[ 42.849221][ C1] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1783)
[ 42.849389][ C1] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1783)
[ 42.849559][ C1] ? __pfx_call_timer_fn (kernel/time/timer.c:1770)
[ 42.849729][ C1] ? mark_lock (kernel/locking/lockdep.c:4656 (discriminator 3))
[ 42.849966][ C1] __run_timers (kernel/time/timer.c:1845 kernel/time/timer.c:2418)
[ 42.850138][ C1] ? __pfx_br_forward_delay_timer_expired (net/bridge/br_stp_timer.c:80)
[ 42.850351][ C1] ? __pfx___run_timers (kernel/time/timer.c:2389)
[ 42.850523][ C1] ? __lock_release (kernel/locking/lockdep.c:5430)
[ 42.850695][ 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)
[ 42.850865][ C1] ? __pfx_do_raw_spin_lock (kernel/locking/spinlock_debug.c:114)
[ 42.851036][ C1] ? lock_acquire (kernel/locking/lockdep.c:5727)
[ 42.851208][ C1] ? timer_expire_remote (kernel/time/timer.c:2429 kernel/time/timer.c:2422 kernel/time/timer.c:2181)
[ 42.851381][ C1] timer_expire_remote (kernel/time/timer.c:2430 kernel/time/timer.c:2422 kernel/time/timer.c:2181)
[ 42.851659][ C1] tmigr_handle_remote_cpu (./arch/x86/include/asm/irqflags.h:26 ./arch/x86/include/asm/irqflags.h:67 ./arch/x86/include/asm/irqflags.h:127 kernel/time/timer_migration.c:925)
[ 42.851829][ C1] ? __pfx_tmigr_handle_remote_cpu (kernel/time/timer_migration.c:869)
[ 42.852046][ C1] tmigr_handle_remote_up (kernel/time/timer_migration.c:1003)
[ 42.852217][ C1] tmigr_handle_remote (kernel/time/timer_migration.c:488 kernel/time/timer_migration.c:1061)
[ 42.852388][ 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)
[ 42.852558][ C1] ? __pfx_tmigr_handle_remote (kernel/time/timer_migration.c:1026)
[ 42.852727][ C1] ? __pfx_do_raw_spin_lock (kernel/locking/spinlock_debug.c:114)
[ 42.852896][ C1] ? lockdep_hardirqs_on_prepare.part.0 (kernel/locking/lockdep.c:4292 kernel/locking/lockdep.c:4359)
[ 42.853213][ C1] __do_softirq (kernel/softirq.c:554)
[ 42.853384][ C1] irq_exit_rcu (kernel/softirq.c:428 kernel/softirq.c:633 kernel/softirq.c:645)
[ 42.853521][ C1] sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1043 arch/x86/kernel/apic/apic.c:1043)
[ 42.853692][ C1]
[ 42.853779][ C1]
[ 42.853866][ C1] asm_sysvec_apic_timer_interrupt (./arch/x86/include/asm/idtentry.h:702)
[ 42.854081][ C1] RIP: 0010:_raw_spin_unlock_irqrestore (./include/linux/spinlock_api_smp.h:152 kernel/locking/spinlock.c:194)
[ 42.854298][ C1] Code: 10 e8 81 31 79 fd 48 89 ef e8 b9 a1 79 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 f5 8b ad 74 <74> 0e 5b 5d c3 cc cc cc cc e8 0f 00 9d fd eb dc 0f 1f 44 00 00 5b
All code
========
0: 10 e8 adc %ch,%al
2: 81 31 79 fd 48 89 xorl $0x8948fd79,(%rcx)
8: ef out %eax,(%dx)
9: e8 b9 a1 79 fd call 0xfffffffffd79a1c7
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 f5 8b ad 74 decl %gs:0x74ad8bf5(%rip) # 0x74ad8c1f
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 0f 00 9d fd call 0xfffffffffd9d0047
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 0f 00 9d fd call 0xfffffffffd9d001d
e: eb dc jmp 0xffffffffffffffec
10: 0f 1f 44 00 00 nopl 0x0(%rax,%rax,1)
15: 5b pop %rbx
[ 42.855014][ C1] RSP: 0018:ffffc9000074f440 EFLAGS: 00000282
[ 42.855232][ C1] RAX: 0000000000000002 RBX: 0000000000000200 RCX: 1ffffffff1a32609
[ 42.855489][ C1] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffffff8b563de1
[ 42.855742][ C1] RBP: ffff888001041080 R08: 0000000000000001 R09: fffffbfff1a32e8a
[ 42.855995][ C1] R10: ffffffff8d197457 R11: 0000000000001000 R12: ffff888001041080
[ 42.856360][ C1] R13: ffffea0000268000 R14: ffff8880010433c0 R15: ffff888009a04000
[ 42.856615][ C1] ? _raw_spin_unlock_irqrestore (./include/linux/spinlock_api_smp.h:151 kernel/locking/spinlock.c:194)
[ 42.856825][ C1] get_partial_node.part.0 (mm/slub.c:2623)
[ 42.856998][ C1] ___slab_alloc (mm/slub.c:2583 mm/slub.c:2701 mm/slub.c:3505)
[ 42.857166][ C1] ? fs_reclaim_acquire (mm/page_alloc.c:3715 mm/page_alloc.c:3706)
[ 42.857335][ C1] ? p9_fcall_init (net/9p/client.c:232)
[ 42.857511][ C1] ? __pfx___lock_release (kernel/locking/lockdep.c:5406)
[ 42.857810][ C1] ? p9_fcall_init (net/9p/client.c:232)
[ 42.857986][ C1] ? __kmalloc (mm/slub.c:3610 mm/slub.c:3663 mm/slub.c:3835 mm/slub.c:3965 mm/slub.c:3979)
[ 42.858156][ C1] __kmalloc (mm/slub.c:3610 mm/slub.c:3663 mm/slub.c:3835 mm/slub.c:3965 mm/slub.c:3979)
[ 42.858286][ C1] p9_fcall_init (net/9p/client.c:232)
[ 42.858455][ C1] p9_tag_alloc (net/9p/client.c:297)
[ 42.858627][ C1] ? __pfx_p9_tag_alloc (net/9p/client.c:277)
[ 42.858796][ C1] ? validate_chain (./include/linux/hash.h:78 kernel/locking/lockdep.c:3759 kernel/locking/lockdep.c:3782 kernel/locking/lockdep.c:3837)
[ 42.858964][ 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)
[ 42.859136][ C1] p9_client_prepare_req (net/9p/client.c:641)
[ 42.859312][ 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)
[ 42.859481][ C1] ? __pfx_p9_client_prepare_req (net/9p/client.c:625)
[ 42.859692][ C1] ? __lock_acquire (kernel/locking/lockdep.c:5137)
[ 42.859861][ C1] p9_client_rpc (net/9p/client.c:688 (discriminator 4))
[ 42.860031][ C1] ? __pfx_p9_client_rpc (net/9p/client.c:672)
[ 42.860200][ C1] ? __lock_release (kernel/locking/lockdep.c:5430)
[ 42.860369][ C1] ? list_lru_add (mm/list_lru.c:106)
[ 42.860540][ C1] ? __pfx___lock_release (kernel/locking/lockdep.c:5406)
[ 42.860832][ C1] ? __pfx_lock_acquire.part.0 (kernel/locking/lockdep.c:5719)
[ 42.861002][ C1] ? find_held_lock (kernel/locking/lockdep.c:5244)
[ 42.861171][ C1] ? __lock_release (kernel/locking/lockdep.c:5430)
[ 42.861338][ C1] ? __pfx___lock_release (kernel/locking/lockdep.c:5406)
[ 42.861506][ C1] p9_client_clunk (net/9p/client.c:1438 (discriminator 3))
[ 42.861675][ C1] v9fs_dentry_release (fs/9p/vfs_dentry.c:54)
[ 42.861845][ C1] __dentry_kill (fs/dcache.c:608)
[ 42.862018][ C1] ? __pfx_kfree_link (fs/libfs.c:1573)
[ 42.862193][ C1] dput.part.0 (fs/dcache.c:845)
[ 42.862362][ C1] walk_component (fs/namei.c:562 fs/namei.c:1027 fs/namei.c:2009)
[ 42.862533][ C1] link_path_walk.part.0.constprop.0 (fs/namei.c:2328)
[ 42.862746][ C1] ? __pfx_link_path_walk.part.0.constprop.0 (fs/namei.c:2249)
[ 42.862960][ C1] path_openat (fs/namei.c:3795)
[ 42.863131][ C1] ? __pfx_path_openat (fs/namei.c:3781)
[ 42.863301][ C1] ? __lock_acquire (kernel/locking/lockdep.c:5137)
[ 42.863479][ C1] do_filp_open (fs/namei.c:3826)
[ 42.863650][ C1] ? __pfx_do_filp_open (fs/namei.c:3820)
[ 42.863932][ C1] ? find_held_lock (kernel/locking/lockdep.c:5244)
[ 42.864108][ C1] ? __pfx_kfree_link (fs/libfs.c:1573)
[ 42.864277][ C1] ? __pfx_do_raw_spin_lock (kernel/locking/spinlock_debug.c:114)
[ 42.864445][ C1] ? alloc_fd (fs/file.c:555 (discriminator 10))
[ 42.864573][ 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)
[ 42.864742][ C1] ? _raw_spin_unlock (./arch/x86/include/asm/preempt.h:94 ./include/linux/spinlock_api_smp.h:143 kernel/locking/spinlock.c:186)
[ 42.864912][ C1] ? alloc_fd (fs/file.c:555 (discriminator 10))
[ 42.865041][ C1] do_sys_openat2 (fs/open.c:1406)
[ 42.865214][ C1] ? __pfx_do_sys_openat2 (fs/open.c:1392)
[ 42.865487][ C1] ? __pfx___debug_check_no_obj_freed (lib/debugobjects.c:960)
[ 42.865701][ 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)
[ 42.865872][ C1] __x64_sys_openat (fs/open.c:1432)
[ 42.866043][ C1] ? __pfx___x64_sys_openat (fs/open.c:1432)
[ 42.866213][ C1] do_syscall_64 (arch/x86/entry/common.c:52 arch/x86/entry/common.c:83)
[ 42.866384][ C1] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:129)
[ 42.866593][ C1] RIP: 0033:0x7f3c0e1030e8
[ 42.866770][ C1] Code: f9 41 89 f0 41 83 e2 40 75 30 89 f0 25 00 00 41 00 3d 00 00 41 00 74 22 44 89 c2 4c 89 ce bf 9c ff ff ff b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 30 c3 0f 1f 80 00 00 00 00 48 8d 44 24 08 c7
All code
========
0: f9 stc
1: 41 89 f0 mov %esi,%r8d
4: 41 83 e2 40 and $0x40,%r10d
8: 75 30 jne 0x3a
a: 89 f0 mov %esi,%eax
c: 25 00 00 41 00 and $0x410000,%eax
11: 3d 00 00 41 00 cmp $0x410000,%eax
16: 74 22 je 0x3a
18: 44 89 c2 mov %r8d,%edx
1b: 4c 89 ce mov %r9,%rsi
1e: bf 9c ff ff ff mov $0xffffff9c,%edi
23: b8 01 01 00 00 mov $0x101,%eax
28: 0f 05 syscall
2a:* 48 3d 00 f0 ff ff cmp $0xfffffffffffff000,%rax <-- trapping instruction
30: 77 30 ja 0x62
32: c3 ret
33: 0f 1f 80 00 00 00 00 nopl 0x0(%rax)
3a: 48 8d 44 24 08 lea 0x8(%rsp),%rax
3f: c7 .byte 0xc7
Code starting with the faulting instruction
===========================================
0: 48 3d 00 f0 ff ff cmp $0xfffffffffffff000,%rax
6: 77 30 ja 0x38
8: c3 ret
9: 0f 1f 80 00 00 00 00 nopl 0x0(%rax)
10: 48 8d 44 24 08 lea 0x8(%rsp),%rax
15: c7 .byte 0xc7
[ 42.867480][ C1] RSP: 002b:00007fffe2efb9e8 EFLAGS: 00000287 ORIG_RAX: 0000000000000101
[ 42.867739][ C1] RAX: ffffffffffffffda RBX: 00007fffe2efbc6f RCX: 00007f3c0e1030e8
[ 42.867993][ C1] RDX: 0000000000080000 RSI: 00007fffe2efba60 RDI: 00000000ffffff9c
[ 42.868245][ C1] RBP: 00007fffe2efba50 R08: 0000000000080000 R09: 00007fffe2efba60
[ 42.868605][ C1] R10: 0000000000000000 R11: 0000000000000287 R12: 00007fffe2efba67
Finger prints:
dump_stack_lvl:mark_lock_irq:mark_lock:mark_usage