====================================== | [ 560.500662][ C2] ret_from_fork_asm (arch/x86/entry/entry_64.S:258) | [ 560.500664][ C2] | [ 560.500665][ C2] | [ 560.500665][ C2] stack backtrace: [ 560.500672][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 560.500675][ C2] Workqueue: pm pm_runtime_work [ 560.500682][ C2] Call Trace: [ 560.500685][ C2] [ 560.500689][ C2] dump_stack_lvl (lib/dump_stack.c:123) [ 560.500695][ C2] print_irq_inversion_bug.part.0 (kernel/locking/lockdep.c:4127) [ 560.500699][ C2] mark_lock_irq (kernel/locking/lockdep.c:4295) [ 560.500706][ C2] mark_lock (kernel/locking/lockdep.c:4753) [ 560.500709][ C2] mark_usage (kernel/locking/lockdep.c:4639) [ 560.500712][ C2] __lock_acquire (kernel/locking/lockdep.c:5191) [ 560.500716][ C2] lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 560.500719][ C2] ? console_lock_spinning_enable (kernel/printk/printk.c:1924) [ 560.500723][ C2] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:751) [ 560.500729][ C2] ? lock_acquire (./include/trace/events/lock.h:24 kernel/locking/lockdep.c:5831) [ 560.500732][ C2] ? console_lock_spinning_enable (kernel/printk/printk.c:1924) [ 560.500736][ C2] console_lock_spinning_enable (kernel/printk/printk.c:1925) [ 560.500739][ C2] ? console_lock_spinning_enable (kernel/printk/printk.c:1924) [ 560.500742][ C2] console_emit_next_record (kernel/printk/printk.c:3050 (discriminator 3) kernel/printk/printk.c:3137 (discriminator 3)) [ 560.500746][ C2] ? devkmsg_read (kernel/printk/printk.c:3082) [ 560.500750][ C2] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:751) [ 560.500756][ C2] console_flush_all (kernel/printk/printk.c:3227) [ 560.500760][ C2] ? console_emit_next_record (kernel/printk/printk.c:3188) [ 560.500763][ C2] ? __lock_acquire (kernel/locking/lockdep.c:5237) [ 560.500768][ C2] console_unlock (kernel/printk/printk.c:3286 kernel/printk/printk.c:3325) [ 560.500771][ C2] ? console_flush_all (kernel/printk/printk.c:3320) [ 560.500774][ C2] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:751) [ 560.500778][ C2] ? irq_work_single (kernel/irq_work.c:222) [ 560.500781][ C2] ? irq_work_single (kernel/irq_work.c:222) [ 560.500786][ C2] wake_up_klogd_work_func (kernel/printk/printk.c:4529) [ 560.500789][ C2] irq_work_single (kernel/irq_work.c:222) [ 560.500793][ C2] irq_work_run_list (kernel/irq_work.c:251 (discriminator 3)) [ 560.500796][ C2] ? irq_work_tick (kernel/irq_work.c:277) [ 560.500799][ C2] update_process_times (kernel/time/timer.c:2478) [ 560.500803][ C2] tick_nohz_handler (kernel/time/tick-sched.c:277 kernel/time/tick-sched.c:297) [ 560.500806][ C2] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:751) [ 560.500810][ C2] __run_hrtimer (kernel/time/hrtimer.c:1761) [ 560.500814][ C2] ? tick_do_update_jiffies64.part.0 (kernel/time/tick-sched.c:285) [ 560.500818][ C2] __hrtimer_run_queues (./include/linux/timerqueue.h:25 kernel/time/hrtimer.c:1805) [ 560.500822][ C2] ? hrtimer_interrupt (kernel/time/hrtimer.c:640 (discriminator 2) kernel/time/hrtimer.c:1869 (discriminator 2)) [ 560.500826][ C2] ? __run_hrtimer (kernel/time/hrtimer.c:1795) [ 560.500829][ C2] ? kvm_clock_get_cycles (./arch/x86/include/asm/preempt.h:95 arch/x86/kernel/kvmclock.c:80 arch/x86/kernel/kvmclock.c:86) [ 560.500837][ C2] hrtimer_interrupt (kernel/time/hrtimer.c:1890) [ 560.500841][ C2] ? __common_interrupt (./include/asm-generic/irq_regs.h:28 arch/x86/kernel/irq.c:295) [ 560.500849][ C2] __sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1057) [ 560.500852][ C2] sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1050 arch/x86/kernel/apic/apic.c:1050) [ 560.500856][ C2] [ 560.500857][ C2] [ 560.500859][ C2] asm_sysvec_apic_timer_interrupt (./arch/x86/include/asm/idtentry.h:702) [ 560.500861][ C2] RIP: 0010:_raw_spin_unlock_irq (./include/linux/spinlock_api_smp.h:160 kernel/locking/spinlock.c:202) [ 560.500865][ C2] Code: 0f 1f 44 00 00 55 48 8b 74 24 08 48 89 fd 48 83 c7 18 e8 85 da f9 fd 48 89 ef e8 ad 29 fa fd e8 58 df 1d fe fb bf 01 00 00 00 fd 5d ee fd 65 8b 05 76 7c 03 02 85 c0 74 02 5d c3 e8 bb 76 aa All code ======== 0: 0f 1f 44 00 00 nopl 0x0(%rax,%rax,1) 5: 55 push %rbp 6: 48 8b 74 24 08 mov 0x8(%rsp),%rsi b: 48 89 fd mov %rdi,%rbp e: 48 83 c7 18 add $0x18,%rdi 12: e8 85 da f9 fd call 0xfffffffffdf9da9c 17: 48 89 ef mov %rbp,%rdi 1a: e8 ad 29 fa fd call 0xfffffffffdfa29cc 1f: e8 58 df 1d fe call 0xfffffffffe1ddf7c 24: fb sti 25: bf 01 00 00 00 mov $0x1,%edi 2a:* e8 fd 5d ee fd call 0xfffffffffdee5e2c <-- trapping instruction 2f: 65 8b 05 76 7c 03 02 mov %gs:0x2037c76(%rip),%eax # 0x2037cac 36: 85 c0 test %eax,%eax 38: 74 02 je 0x3c 3a: 5d pop %rbp 3b: c3 ret 3c: e8 .byte 0xe8 3d: bb .byte 0xbb 3e: 76 aa jbe 0xffffffffffffffea Code starting with the faulting instruction =========================================== 0: e8 fd 5d ee fd call 0xfffffffffdee5e02 5: 65 8b 05 76 7c 03 02 mov %gs:0x2037c76(%rip),%eax # 0x2037c82 c: 85 c0 test %eax,%eax e: 74 02 je 0x12 10: 5d pop %rbp 11: c3 ret 12: e8 .byte 0xe8 13: bb .byte 0xbb 14: 76 aa jbe 0xffffffffffffffc0 [ 560.500868][ C2] RSP: 0018:ffffc900001ffc88 EFLAGS: 00000202 [ 560.500871][ C2] RAX: 0000000000008443 RBX: ffffffff8f01d264 RCX: ffffffff8bbcd1ff [ 560.500873][ C2] RDX: 0000000000000000 RSI: ffffffff8e1ea6cb RDI: 0000000000000001 [ 560.500875][ C2] RBP: ffff888036335880 R08: 0000000000000001 R09: 0000000000000001 [ 560.500877][ C2] R10: ffffffff8f01c217 R11: ffffffff9096b760 R12: ffff8880058e0a58 [ 560.500879][ C2] R13: ffff888001c60040 R14: ffff888001965540 R15: 0000000000000000 [ 560.500882][ C2] ? trace_irq_enable.constprop.0 (./arch/x86/include/asm/bitops.h:227 ./arch/x86/include/asm/bitops.h:239 ./include/asm-generic/bitops/instrumented-non-atomic.h:142 ./include/linux/cpumask.h:646 ./include/linux/cpumask.h:1205 ./include/trace/events/preemptirq.h:40) [ 560.500890][ C2] ? _raw_spin_unlock_irq (./arch/x86/include/asm/irqflags.h:42 ./arch/x86/include/asm/irqflags.h:119 ./include/linux/spinlock_api_smp.h:159 kernel/locking/spinlock.c:202) [ 560.500893][ C2] process_one_work (kernel/workqueue.c:3207) [ 560.500899][ C2] ? pwq_dec_nr_in_flight (kernel/workqueue.c:3138) [ 560.500905][ C2] ? assign_work (kernel/workqueue.c:1204) [ 560.500909][ C2] worker_thread (kernel/workqueue.c:3313 kernel/workqueue.c:3400) [ 560.500915][ C2] ? rescuer_thread (kernel/workqueue.c:3346) [ 560.500918][ C2] kthread (kernel/kthread.c:463) [ 560.500922][ C2] ? kthread_is_per_cpu (kernel/kthread.c:412) [ 560.500925][ C2] ? ret_from_fork (arch/x86/kernel/process.c:147) [ 560.500927][ C2] ? __lock_release (kernel/locking/lockdep.c:5536) [ 560.500930][ C2] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:751) [ 560.500934][ C2] ? kthread_is_per_cpu (kernel/kthread.c:412) [ 560.500938][ C2] ret_from_fork (arch/x86/kernel/process.c:154) [ 560.500940][ C2] ? kthread_is_per_cpu (kernel/kthread.c:412) Finger prints: ret_from_fork_asm:dump_stack_lvl:mark_lock_irq:mark_lock:mark_usage