====================================== | # 137.33 [+0.00] [ 960.387950] [ C0] hardirqs last enabled at (42071744): asm_common_interrupt (./arch/x86/include/asm/idtentry.h:688) | # 137.34 [+0.01] [ 960.387958] [ C0] hardirqs last disabled at (42071745): sysvec_apic_timer_interrupt (./arch/x86/include/asm/hardirq.h:78 arch/x86/kernel/apic/apic.c:1052) | # 137.35 [+0.01] [ 960.387962] [ C0] softirqs last enabled at (41988006): handle_softirqs (kernel/softirq.c:469 (discriminator 2) kernel/softirq.c:650 (discriminator 2)) | # 137.36 [+0.01] [ 960.387966] [ C0] softirqs last disabled at (41988011): irq_exit_rcu (kernel/softirq.c:657 kernel/softirq.c:496 kernel/softirq.c:723 kernel/softirq.c:739) # 137.38 [+0.01] [ 960.387973] [ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 # 137.39 [+0.01] [ 960.387976] [ C0] RIP: 0010:console_emit_next_record (kernel/printk/printk.c:3055) # 137.40 [+0.01] [ 960.387980] [ C0] Code: ca 7f 08 84 d2 0f 85 ad 01 00 00 88 45 00 e8 52 3f 00 00 48 83 7c 24 08 00 0f 85 e8 00 00 00 9c 58 f6 c4 02 0f 85 01 01 00 00 <48> b8 00 00 00 00 00 fc ff df c7 04 03 00 00 00 00 c7 44 18 08 00 All code ======== 0: ca 7f 08 lret $0x87f 3: 84 d2 test %dl,%dl 5: 0f 85 ad 01 00 00 jne 0x1b8 b: 88 45 00 mov %al,0x0(%rbp) e: e8 52 3f 00 00 call 0x3f65 13: 48 83 7c 24 08 00 cmpq $0x0,0x8(%rsp) 19: 0f 85 e8 00 00 00 jne 0x107 1f: 9c pushf 20: 58 pop %rax 21: f6 c4 02 test $0x2,%ah 24: 0f 85 01 01 00 00 jne 0x12b 2a:* 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax <-- trapping instruction 31: fc ff df 34: c7 04 03 00 00 00 00 movl $0x0,(%rbx,%rax,1) 3b: c7 .byte 0xc7 3c: 44 18 08 sbb %r9b,(%rax) ... Code starting with the faulting instruction =========================================== 0: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax 7: fc ff df a: c7 04 03 00 00 00 00 movl $0x0,(%rbx,%rax,1) 11: c7 .byte 0xc7 12: 44 18 08 sbb %r9b,(%rax) ... # 137.42 [+0.02] [ 960.387982] [ C0] RSP: 0018:ffa00000000077a8 EFLAGS: 00000246 # 137.42 [+0.01] [ 960.387984] [ C0] RAX: 0000000000000096 RBX: 1ff4000000000ef9 RCX: ffffffffad2f3047 # 137.43 [+0.01] [ 960.387986] [ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 # 137.44 [+0.01] [ 960.387987] [ C0] RBP: ffa0000000007978 R08: 0000000000000000 R09: 0000000000000001 # 137.45 [+0.01] [ 960.387987] [ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001 # 137.45 [+0.01] [ 960.387988] [ C0] R13: 0000000000000205 R14: ffffffffb0820ec0 R15: ffffffffb0820ec0 # 137.46 [+0.01] [ 960.387991] [ C0] FS: 0000000000000000(0000) GS:ff11000083ce3000(0000) knlGS:0000000000000000 # 137.47 [+0.01] [ 960.387994] [ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 # 137.48 [+0.01] [ 960.387996] [ C0] CR2: 000056114d2f3000 CR3: 00000000118b2001 CR4: 0000000000771ef0 # 137.49 [+0.01] [ 960.387997] [ C0] PKRU: 55555554 # 137.49 [+0.00] [ 960.387998] [ C0] Call Trace: # 137.49 [+0.00] [ 960.388000] [ C0] # 137.50 [+0.00] [ 960.388005] [ C0] ? devkmsg_read (kernel/printk/printk.c:3055) # 137.50 [+0.00] [ 960.388009] [ C0] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) # 137.51 [+0.01] [ 960.388012] [ C0] ? lock_acquire (kernel/locking/lockdep.c:5833) # 137.51 [+0.00] [ 960.388014] [ C0] ? console_flush_all (./include/linux/rcupdate.h:336 (discriminator 1) ./include/linux/srcu.h:335 (discriminator 1) kernel/printk/printk.c:289 (discriminator 1) kernel/printk/printk.c:3176 (discriminator 1)) # 137.52 [+0.01] [ 960.388018] [ C0] console_flush_all (kernel/printk/printk.c:3200) # 137.52 [+0.01] [ 960.388022] [ C0] ? console_emit_next_record (kernel/printk/printk.c:3161) # 137.53 [+0.01] [ 960.388028] [ C0] console_unlock (kernel/printk/printk.c:3258 kernel/printk/printk.c:3298) # 137.53 [+0.00] [ 960.388030] [ C0] ? console_flush_all (kernel/printk/printk.c:3293) # 137.54 [+0.01] [ 960.388031] [ C0] ? vprintk_emit (kernel/printk/printk.c:2422 (discriminator 1)) # 137.54 [+0.00] [ 960.388035] [ C0] ? __down_trylock_console_sem (kernel/printk/printk.c:333) # 137.55 [+0.01] [ 960.388037] [ C0] ? vprintk_emit (kernel/printk/printk.c:2422 (discriminator 1)) # 137.56 [+0.00] [ 960.388040] [ C0] vprintk_emit (kernel/printk/printk.c:2423) # 137.56 [+0.00] [ 960.388043] [ C0] ? wake_up_klogd_work_func (kernel/printk/printk.c:2370) # 137.57 [+0.01] [ 960.388046] [ C0] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1)) # 137.57 [+0.01] [ 960.388050] [ C0] _printk (kernel/printk/printk.c:2443) # 137.57 [+0.00] [ 960.388054] [ C0] ? snapshot_read.cold (kernel/printk/printk.c:2443) # 137.58 [+0.01] [ 960.388057] [ C0] ? lockdep_hardirqs_on_prepare.part.0 (kernel/locking/lockdep.c:470 (discriminator 6) kernel/locking/lockdep.c:4411 (discriminator 6)) # 137.59 [+0.01] [ 960.388060] [ C0] ? cs_watchdog_read (kernel/time/clocksource.c:269) # 137.59 [+0.01] [ 960.388065] [ C0] ? clocksource_watchdog (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 kernel/time/clocksource.c:491) # 137.60 [+0.01] [ 960.388069] [ C0] clocksource_watchdog.cold (kernel/time/clocksource.c:508) # 137.60 [+0.01] [ 960.388074] [ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) # 137.61 [+0.01] [ 960.388076] [ C0] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) # 137.61 [+0.01] [ 960.388078] [ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) # 137.62 [+0.01] [ 960.388081] [ C0] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) # 137.63 [+0.01] [ 960.388083] [ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1)) # 137.63 [+0.01] [ 960.388086] [ C0] ? lock_acquire (kernel/locking/lockdep.c:5833) # 137.63 [+0.00] [ 960.388087] [ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) # 137.64 [+0.01] [ 960.388089] [ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) # 137.65 [+0.01] [ 960.388092] [ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) # 137.65 [+0.01] [ 960.388093] [ C0] call_timer_fn (kernel/time/timer.c:1748) # 137.66 [+0.00] [ 960.388094] [ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) # 137.66 [+0.01] [ 960.388096] [ C0] ? trace_timer_expire_exit (kernel/time/timer.c:1724) # 137.67 [+0.01] [ 960.388101] [ C0] ? _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) # 137.67 [+0.01] [ 960.388106] [ C0] __run_timers (kernel/time/timer.c:1799 kernel/time/timer.c:2372) # 137.68 [+0.00] [ 960.388108] [ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) # 137.68 [+0.01] [ 960.388113] [ C0] ? call_timer_fn (kernel/time/timer.c:2343) # 137.69 [+0.01] [ 960.388114] [ C0] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1)) # 137.69 [+0.01] [ 960.388122] [ C0] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) # 137.70 [+0.01] [ 960.388124] [ C0] ? run_timer_softirq (kernel/time/timer.c:2384 kernel/time/timer.c:2393 kernel/time/timer.c:2401) # 137.70 [+0.01] [ 960.388128] [ C0] run_timer_softirq (kernel/time/timer.c:2385 kernel/time/timer.c:2393 kernel/time/timer.c:2401) # 137.71 [+0.01] [ 960.388130] [ C0] ? __run_timers (kernel/time/timer.c:2400) # 137.71 [+0.00] [ 960.388133] [ C0] ? trace_softirq_entry (./include/trace/events/irq.h:128 (discriminator 33)) # 137.72 [+0.01] [ 960.388134] [ C0] handle_softirqs (kernel/softirq.c:623) # 137.72 [+0.01] [ 960.388139] [ C0] ? trace_softirq_exit (kernel/softirq.c:580) # 137.73 [+0.01] [ 960.388140] [ C0] ? 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) # 137.73 [+0.01] [ 960.388144] [ C0] irq_exit_rcu (kernel/softirq.c:657 kernel/softirq.c:496 kernel/softirq.c:723 kernel/softirq.c:739) # 137.74 [+0.00] [ 960.388145] [ C0] common_interrupt (arch/x86/kernel/irq.c:318 (discriminator 49)) # 137.74 [+0.01] [ 960.388148] [ C0] # 137.75 [+0.00] [ 960.388149] [ C0] # 137.75 [+0.00] [ 960.388149] [ C0] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4473) # 137.76 [+0.01] [ 960.388152] [ C0] asm_common_interrupt (./arch/x86/include/asm/idtentry.h:688) # 137.76 [+0.01] [ 960.388154] [ C0] RIP: 0010:default_idle_call (kernel/sched/idle.c:131) # 137.77 [+0.01] [ 960.388157] [ C0] Code: 3c 24 e8 3b e1 ff ff e8 c6 eb ff ff 90 e8 b0 f2 ff ff bf ff ff ff ff 89 c6 e8 d4 90 e0 fd e8 ff 6b e0 fd e8 6a 59 0c fe fb 90 90 0f 0b 90 eb af 90 65 48 8b 05 18 b5 0d 02 48 8b 00 a8 10 75 All code ======== 0: 3c 24 cmp $0x24,%al 2: e8 3b e1 ff ff call 0xffffffffffffe142 7: e8 c6 eb ff ff call 0xffffffffffffebd2 c: 90 nop d: e8 b0 f2 ff ff call 0xfffffffffffff2c2 12: bf ff ff ff ff mov $0xffffffff,%edi 17: 89 c6 mov %eax,%esi 19: e8 d4 90 e0 fd call 0xfffffffffde090f2 1e: e8 ff 6b e0 fd call 0xfffffffffde06c22 23: e8 6a 59 0c fe call 0xfffffffffe0c5992 28: fb sti 29: 90 nop 2a:* c3 ret <-- trapping instruction 2b: 90 nop 2c: 0f 0b ud2 2e: 90 nop 2f: eb af jmp 0xffffffffffffffe0 31: 90 nop 32: 65 48 8b 05 18 b5 0d mov %gs:0x20db518(%rip),%rax # 0x20db552 39: 02 3a: 48 8b 00 mov (%rax),%rax 3d: a8 10 test $0x10,%al 3f: 75 .byte 0x75 Code starting with the faulting instruction =========================================== 0: c3 ret 1: 90 nop 2: 0f 0b ud2 4: 90 nop 5: eb af jmp 0xffffffffffffffb6 7: 90 nop 8: 65 48 8b 05 18 b5 0d mov %gs:0x20db518(%rip),%rax # 0x20db528 f: 02 10: 48 8b 00 mov (%rax),%rax 13: a8 10 test $0x10,%al 15: 75 .byte 0x75 # 137.79 [+0.02] [ 960.388158] [ C0] RSP: 0018:ffffffffb0007e10 EFLAGS: 00000282 # 137.79 [+0.01] [ 960.388159] [ C0] RAX: 000000000280afa9 RBX: ffffffffb001e680 RCX: ffffffffaf461af6 # 137.80 [+0.01] [ 960.388160] [ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 # 137.81 [+0.01] [ 960.388161] [ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 # 137.82 [+0.01] [ 960.388161] [ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 1ffffffff6000fc3 # 137.83 [+0.01] [ 960.388162] [ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 # 137.83 [+0.01] [ 960.388166] [ C0] ? default_idle_call (./arch/x86/include/asm/irqflags.h:42 ./arch/x86/include/asm/irqflags.h:119 kernel/sched/idle.c:129) # 137.84 [+0.01] [ 960.388171] [ C0] cpuidle_idle_call (kernel/sched/idle.c:191) # 137.84 [+0.01] [ 960.388175] [ C0] ? arch_cpu_idle_exit+0x40/0x40 # 137.85 [+0.01] [ 960.388177] [ C0] ? mark_tsc_async_resets (arch/x86/kernel/tsc_sync.c:52) # 137.86 [+0.01] [ 960.388179] [ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1)) # 137.86 [+0.01] [ 960.388181] [ C0] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) # 137.87 [+0.01] [ 960.388186] [ C0] do_idle (kernel/sched/idle.c:330) # 137.87 [+0.00] [ 960.388189] [ C0] cpu_startup_entry (kernel/sched/idle.c:427) # 137.88 [+0.00] [ 960.388192] [ C0] rest_init (init/main.c:757) # 137.88 [+0.00] [ 960.388195] [ C0] start_kernel (init/main.c:1111) # 137.89 [+0.00] [ 960.388200] [ C0] x86_64_start_reservations (arch/x86/kernel/head64.c:310) # 137.89 [+0.01] [ 960.388203] [ C0] x86_64_start_kernel (??:?) # 137.90 [+0.01] [ 960.388206] [ C0] common_startup_64 (arch/x86/kernel/head_64.S:419) | # 137.90 [+0.00] [ 984.084401] [ C0] rcu: INFO: rcu_preempt self-detected stall on CPU | # 137.91 [+0.01] [ 984.084437] [ C0] rcu: 0-....: (1 GPs behind) idle=e4bc/1/0x4000000000000004 softirq=581657/581657 fqs=6299 | # 137.92 [+0.01] [ 984.084590] [ C0] rcu: (t=26000 jiffies g=624709 q=1351 ncpus=4) | # 137.94 [+0.01] [ 984.084601] [ C0] Tainted: [L]=SOFTLOCKUP # 137.94 [+0.00] [ 984.084602] [ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 # 137.95 [+0.01] [ 984.084604] [ C0] RIP: 0010:console_flush_all (./include/linux/rcupdate.h:341 (discriminator 1) ./include/linux/srcu.h:451 (discriminator 1) kernel/printk/printk.c:303 (discriminator 1) kernel/printk/printk.c:3225 (discriminator 1)) # 137.96 [+0.01] [ 984.084612] [ C0] Code: 89 c6 0f 85 1b fe ff ff 41 83 ff 01 0f 87 7e 01 00 00 be 02 00 00 00 48 c7 c7 e0 16 36 b0 e8 e4 91 05 00 48 c7 c7 f0 16 36 b0 <48> 8d 35 00 00 00 00 e8 51 f6 fd ff 44 89 fe 48 c7 c7 e0 16 36 b0 All code ======== 0: 89 c6 mov %eax,%esi 2: 0f 85 1b fe ff ff jne 0xfffffffffffffe23 8: 41 83 ff 01 cmp $0x1,%r15d c: 0f 87 7e 01 00 00 ja 0x190 12: be 02 00 00 00 mov $0x2,%esi 17: 48 c7 c7 e0 16 36 b0 mov $0xffffffffb03616e0,%rdi 1e: e8 e4 91 05 00 call 0x59207 23: 48 c7 c7 f0 16 36 b0 mov $0xffffffffb03616f0,%rdi 2a:* 48 8d 35 00 00 00 00 lea 0x0(%rip),%rsi # 0x31 <-- trapping instruction 31: e8 51 f6 fd ff call 0xfffffffffffdf687 36: 44 89 fe mov %r15d,%esi 39: 48 c7 c7 e0 16 36 b0 mov $0xffffffffb03616e0,%rdi Code starting with the faulting instruction =========================================== 0: 48 8d 35 00 00 00 00 lea 0x0(%rip),%rsi # 0x7 7: e8 51 f6 fd ff call 0xfffffffffffdf65d c: 44 89 fe mov %r15d,%esi f: 48 c7 c7 e0 16 36 b0 mov $0xffffffffb03616e0,%rdi # 137.98 [+0.02] [ 984.084614] [ C0] RSP: 0018:ffa0000000007880 EFLAGS: 00000286 # 137.98 [+0.01] [ 984.084618] [ C0] RAX: 0000000000000003 RBX: dffffc0000000000 RCX: 0000000000000002 # 137.99 [+0.01] [ 984.084620] [ C0] RDX: 0000000000000002 RSI: 1ffffffff606c2dd RDI: ffffffffb03616f0 # 138.00 [+0.01] [ 984.084620] [ C0] RBP: 0000000000000001 R08: ff11000035224100 R09: fffffbfff603e844 # 138.01 [+0.01] [ 984.084621] [ C0] R10: 0000000000000003 R11: 0000000000000000 R12: ffa0000000007998 # 138.02 [+0.01] [ 984.084622] [ C0] R13: ffa0000000007978 R14: ffffffffb0820ec0 R15: 0000000000000001 # 138.03 [+0.01] [ 984.084625] [ C0] FS: 0000000000000000(0000) GS:ff11000083ce3000(0000) knlGS:0000000000000000 # 138.03 [+0.01] [ 984.084628] [ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 # 138.04 [+0.01] [ 984.084629] [ C0] CR2: 000056114d2f3000 CR3: 00000000118b2001 CR4: 0000000000771ef0 # 138.05 [+0.01] [ 984.084630] [ C0] PKRU: 55555554 # 138.05 [+0.00] [ 984.084631] [ C0] Call Trace: # 138.06 [+0.00] [ 984.084632] [ C0] # 138.06 [+0.00] [ 984.084635] [ C0] ? console_emit_next_record (kernel/printk/printk.c:3161) # 138.07 [+0.01] [ 984.084642] [ C0] console_unlock (kernel/printk/printk.c:3258 kernel/printk/printk.c:3298) # 138.07 [+0.00] [ 984.084645] [ C0] ? console_flush_all (kernel/printk/printk.c:3293) # 138.08 [+0.01] [ 984.084646] [ C0] ? vprintk_emit (kernel/printk/printk.c:2422 (discriminator 1)) # 138.08 [+0.00] [ 984.084650] [ C0] ? __down_trylock_console_sem (kernel/printk/printk.c:333) # 138.09 [+0.01] [ 984.084653] [ C0] ? vprintk_emit (kernel/printk/printk.c:2422 (discriminator 1)) # 138.09 [+0.00] [ 984.084656] [ C0] vprintk_emit (kernel/printk/printk.c:2423) # 138.10 [+0.00] [ 984.084659] [ C0] ? wake_up_klogd_work_func (kernel/printk/printk.c:2370) # 138.10 [+0.01] [ 984.084662] [ C0] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1)) # 138.11 [+0.01] [ 984.084668] [ C0] _printk (kernel/printk/printk.c:2443) # 138.11 [+0.00] [ 984.084671] [ C0] ? snapshot_read.cold (kernel/printk/printk.c:2443) # 138.12 [+0.01] [ 984.084674] [ C0] ? lockdep_hardirqs_on_prepare.part.0 (kernel/locking/lockdep.c:470 (discriminator 6) kernel/locking/lockdep.c:4411 (discriminator 6)) # 138.12 [+0.01] [ 984.084677] [ C0] ? cs_watchdog_read (kernel/time/clocksource.c:269) # 138.13 [+0.01] [ 984.084685] [ C0] ? clocksource_watchdog (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 kernel/time/clocksource.c:491) # 138.13 [+0.01] [ 984.084689] [ C0] clocksource_watchdog.cold (kernel/time/clocksource.c:508) # 138.14 [+0.01] [ 984.084694] [ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) # 138.15 [+0.01] [ 984.084696] [ C0] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) # 138.15 [+0.01] [ 984.084698] [ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) # 138.16 [+0.01] [ 984.084701] [ C0] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) # 138.16 [+0.01] [ 984.084703] [ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1)) # 138.17 [+0.01] [ 984.084705] [ C0] ? lock_acquire (kernel/locking/lockdep.c:5833) # 138.17 [+0.00] [ 984.084707] [ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) # 138.18 [+0.01] [ 984.084709] [ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) # 138.18 [+0.01] [ 984.084712] [ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) # 138.19 [+0.00] [ 984.084713] [ C0] call_timer_fn (kernel/time/timer.c:1748) # 138.19 [+0.00] [ 984.084714] [ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) # 138.20 [+0.00] [ 984.084716] [ C0] ? trace_timer_expire_exit (kernel/time/timer.c:1724) # 138.20 [+0.01] [ 984.084721] [ C0] ? _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) # 138.21 [+0.01] [ 984.084728] [ C0] __run_timers (kernel/time/timer.c:1799 kernel/time/timer.c:2372) # 138.21 [+0.00] [ 984.084729] [ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) # 138.22 [+0.01] [ 984.084734] [ C0] ? call_timer_fn (kernel/time/timer.c:2343) # 138.22 [+0.00] [ 984.084736] [ C0] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1)) # 138.23 [+0.01] [ 984.084743] [ C0] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) # 138.23 [+0.01] [ 984.084745] [ C0] ? run_timer_softirq (kernel/time/timer.c:2384 kernel/time/timer.c:2393 kernel/time/timer.c:2401) # 138.24 [+0.01] [ 984.084749] [ C0] run_timer_softirq (kernel/time/timer.c:2385 kernel/time/timer.c:2393 kernel/time/timer.c:2401) # 138.25 [+0.01] [ 984.084752] [ C0] ? __run_timers (kernel/time/timer.c:2400) # 138.25 [+0.00] [ 984.084754] [ C0] ? trace_softirq_entry (./include/trace/events/irq.h:128 (discriminator 33)) # 138.26 [+0.01] [ 984.084757] [ C0] handle_softirqs (kernel/softirq.c:623) # 138.26 [+0.01] [ 984.084762] [ C0] ? trace_softirq_exit (kernel/softirq.c:580) # 138.27 [+0.01] [ 984.084763] [ C0] ? 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) # 138.27 [+0.01] [ 984.084767] [ C0] irq_exit_rcu (kernel/softirq.c:657 kernel/softirq.c:496 kernel/softirq.c:723 kernel/softirq.c:739) # 138.28 [+0.00] [ 984.084768] [ C0] common_interrupt (arch/x86/kernel/irq.c:318 (discriminator 49)) # 138.28 [+0.01] [ 984.084771] [ C0] # 138.28 [+0.00] [ 984.084772] [ C0] # 138.29 [+0.00] [ 984.084772] [ C0] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4473) # 138.29 [+0.01] [ 984.084775] [ C0] asm_common_interrupt (./arch/x86/include/asm/idtentry.h:688) # 138.30 [+0.01] [ 984.084778] [ C0] RIP: 0010:default_idle_call (kernel/sched/idle.c:131) # 138.30 [+0.01] [ 984.084780] [ C0] Code: 3c 24 e8 3b e1 ff ff e8 c6 eb ff ff 90 e8 b0 f2 ff ff bf ff ff ff ff 89 c6 e8 d4 90 e0 fd e8 ff 6b e0 fd e8 6a 59 0c fe fb 90 90 0f 0b 90 eb af 90 65 48 8b 05 18 b5 0d 02 48 8b 00 a8 10 75 All code ======== 0: 3c 24 cmp $0x24,%al 2: e8 3b e1 ff ff call 0xffffffffffffe142 7: e8 c6 eb ff ff call 0xffffffffffffebd2 c: 90 nop d: e8 b0 f2 ff ff call 0xfffffffffffff2c2 12: bf ff ff ff ff mov $0xffffffff,%edi 17: 89 c6 mov %eax,%esi 19: e8 d4 90 e0 fd call 0xfffffffffde090f2 1e: e8 ff 6b e0 fd call 0xfffffffffde06c22 23: e8 6a 59 0c fe call 0xfffffffffe0c5992 28: fb sti 29: 90 nop 2a:* c3 ret <-- trapping instruction 2b: 90 nop 2c: 0f 0b ud2 2e: 90 nop 2f: eb af jmp 0xffffffffffffffe0 31: 90 nop 32: 65 48 8b 05 18 b5 0d mov %gs:0x20db518(%rip),%rax # 0x20db552 39: 02 3a: 48 8b 00 mov (%rax),%rax 3d: a8 10 test $0x10,%al 3f: 75 .byte 0x75 Code starting with the faulting instruction =========================================== 0: c3 ret 1: 90 nop 2: 0f 0b ud2 4: 90 nop 5: eb af jmp 0xffffffffffffffb6 7: 90 nop 8: 65 48 8b 05 18 b5 0d mov %gs:0x20db518(%rip),%rax # 0x20db528 f: 02 10: 48 8b 00 mov (%rax),%rax 13: a8 10 test $0x10,%al 15: 75 .byte 0x75 # 138.32 [+0.02] [ 984.084781] [ C0] RSP: 0018:ffffffffb0007e10 EFLAGS: 00000282 # 138.33 [+0.01] [ 984.084782] [ C0] RAX: 000000000280afa9 RBX: ffffffffb001e680 RCX: ffffffffaf461af6 # 138.34 [+0.01] [ 984.084783] [ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 # 138.35 [+0.01] [ 984.084784] [ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 # 138.35 [+0.01] [ 984.084785] [ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 1ffffffff6000fc3 # 138.36 [+0.01] [ 984.084785] [ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 # 138.37 [+0.01] [ 984.084789] [ C0] ? default_idle_call (./arch/x86/include/asm/irqflags.h:42 ./arch/x86/include/asm/irqflags.h:119 kernel/sched/idle.c:129) # 138.38 [+0.01] [ 984.084794] [ C0] cpuidle_idle_call (kernel/sched/idle.c:191) # 138.38 [+0.01] [ 984.084797] [ C0] ? arch_cpu_idle_exit+0x40/0x40 # 138.39 [+0.01] [ 984.084800] [ C0] ? mark_tsc_async_resets (arch/x86/kernel/tsc_sync.c:52) # 138.39 [+0.01] [ 984.084802] [ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1)) # 138.40 [+0.01] [ 984.084804] [ C0] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) # 138.40 [+0.01] [ 984.084808] [ C0] do_idle (kernel/sched/idle.c:330) # 138.41 [+0.00] [ 984.084812] [ C0] cpu_startup_entry (kernel/sched/idle.c:427) # 138.41 [+0.01] [ 984.084814] [ C0] rest_init (init/main.c:757) # 138.42 [+0.00] [ 984.084817] [ C0] start_kernel (init/main.c:1111) # 138.42 [+0.00] [ 984.084822] [ C0] x86_64_start_reservations (arch/x86/kernel/head64.c:310) # 138.43 [+0.01] [ 984.084824] [ C0] x86_64_start_kernel (??:?) # 138.43 [+0.01] [ 984.084827] [ C0] common_startup_64 (arch/x86/kernel/head_64.S:419) | # 138.49 [+0.01] [ 1008.387951] [ C0] hardirqs last disabled at (43830125): sysvec_apic_timer_interrupt (./arch/x86/include/asm/hardirq.h:78 arch/x86/kernel/apic/apic.c:1052) | # 138.50 [+0.01] [ 1008.387955] [ C0] softirqs last enabled at (41988006): handle_softirqs (kernel/softirq.c:469 (discriminator 2) kernel/softirq.c:650 (discriminator 2)) | # 138.51 [+0.01] [ 1008.387959] [ C0] softirqs last disabled at (41988011): irq_exit_rcu (kernel/softirq.c:657 kernel/softirq.c:496 kernel/softirq.c:723 kernel/softirq.c:739) | # 138.53 [+0.01] [ 1008.387967] [ C0] Tainted: [L]=SOFTLOCKUP # 138.53 [+0.00] [ 1008.387968] [ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 # 138.54 [+0.01] [ 1008.387970] [ C0] RIP: 0010:console_emit_next_record (kernel/printk/printk.c:3055) # 138.55 [+0.01] [ 1008.387975] [ C0] Code: ca 7f 08 84 d2 0f 85 ad 01 00 00 88 45 00 e8 52 3f 00 00 48 83 7c 24 08 00 0f 85 e8 00 00 00 9c 58 f6 c4 02 0f 85 01 01 00 00 <48> b8 00 00 00 00 00 fc ff df c7 04 03 00 00 00 00 c7 44 18 08 00 All code ======== 0: ca 7f 08 lret $0x87f 3: 84 d2 test %dl,%dl 5: 0f 85 ad 01 00 00 jne 0x1b8 b: 88 45 00 mov %al,0x0(%rbp) e: e8 52 3f 00 00 call 0x3f65 13: 48 83 7c 24 08 00 cmpq $0x0,0x8(%rsp) 19: 0f 85 e8 00 00 00 jne 0x107 1f: 9c pushf 20: 58 pop %rax 21: f6 c4 02 test $0x2,%ah 24: 0f 85 01 01 00 00 jne 0x12b 2a:* 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax <-- trapping instruction 31: fc ff df 34: c7 04 03 00 00 00 00 movl $0x0,(%rbx,%rax,1) 3b: c7 .byte 0xc7 3c: 44 18 08 sbb %r9b,(%rax) ... Code starting with the faulting instruction =========================================== 0: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax 7: fc ff df a: c7 04 03 00 00 00 00 movl $0x0,(%rbx,%rax,1) 11: c7 .byte 0xc7 12: 44 18 08 sbb %r9b,(%rax) ... # 138.57 [+0.02] [ 1008.387977] [ C0] RSP: 0018:ffa00000000077a8 EFLAGS: 00000246 # 138.57 [+0.01] [ 1008.387979] [ C0] RAX: 0000000000000096 RBX: 1ff4000000000ef9 RCX: ffffffffad2f3047 # 138.58 [+0.01] [ 1008.387981] [ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 # 138.59 [+0.01] [ 1008.387981] [ C0] RBP: ffa0000000007978 R08: 0000000000000000 R09: 0000000000000001 # 138.60 [+0.01] [ 1008.387982] [ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001 # 138.61 [+0.01] [ 1008.387983] [ C0] R13: 000000000000023c R14: ffffffffb0820ec0 R15: ffffffffb0820ec0 # 138.62 [+0.01] [ 1008.387986] [ C0] FS: 0000000000000000(0000) GS:ff11000083ce3000(0000) knlGS:0000000000000000 # 138.62 [+0.01] [ 1008.387989] [ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 # 138.63 [+0.01] [ 1008.387990] [ C0] CR2: 000056114d2f3000 CR3: 00000000118b2001 CR4: 0000000000771ef0 # 138.64 [+0.01] [ 1008.387991] [ C0] PKRU: 55555554 # 138.64 [+0.00] [ 1008.387992] [ C0] Call Trace: # 138.65 [+0.00] [ 1008.387993] [ C0] # 138.65 [+0.00] [ 1008.387997] [ C0] ? devkmsg_read (kernel/printk/printk.c:3055) # 138.65 [+0.00] [ 1008.388001] [ C0] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) # 138.66 [+0.01] [ 1008.388005] [ C0] ? lock_acquire (kernel/locking/lockdep.c:5833) # 138.66 [+0.00] [ 1008.388007] [ C0] ? console_flush_all (./include/linux/rcupdate.h:336 (discriminator 1) ./include/linux/srcu.h:335 (discriminator 1) kernel/printk/printk.c:289 (discriminator 1) kernel/printk/printk.c:3176 (discriminator 1)) # 138.67 [+0.01] [ 1008.388010] [ C0] console_flush_all (kernel/printk/printk.c:3200) # 138.68 [+0.01] [ 1008.388015] [ C0] ? console_emit_next_record (kernel/printk/printk.c:3161) # 138.68 [+0.01] [ 1008.388020] [ C0] console_unlock (kernel/printk/printk.c:3258 kernel/printk/printk.c:3298) # 138.69 [+0.00] [ 1008.388023] [ C0] ? console_flush_all (kernel/printk/printk.c:3293) # 138.69 [+0.01] [ 1008.388024] [ C0] ? vprintk_emit (kernel/printk/printk.c:2422 (discriminator 1)) # 138.70 [+0.00] [ 1008.388027] [ C0] ? __down_trylock_console_sem (kernel/printk/printk.c:333) # 138.70 [+0.01] [ 1008.388030] [ C0] ? vprintk_emit (kernel/printk/printk.c:2422 (discriminator 1)) # 138.71 [+0.00] [ 1008.388032] [ C0] vprintk_emit (kernel/printk/printk.c:2423) # 138.71 [+0.00] [ 1008.388036] [ C0] ? wake_up_klogd_work_func (kernel/printk/printk.c:2370) # 138.72 [+0.01] [ 1008.388039] [ C0] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1)) # 138.72 [+0.01] [ 1008.388043] [ C0] _printk (kernel/printk/printk.c:2443) # 138.73 [+0.00] [ 1008.388046] [ C0] ? snapshot_read.cold (kernel/printk/printk.c:2443) # 138.73 [+0.01] [ 1008.388049] [ C0] ? lockdep_hardirqs_on_prepare.part.0 (kernel/locking/lockdep.c:470 (discriminator 6) kernel/locking/lockdep.c:4411 (discriminator 6)) # 138.74 [+0.01] [ 1008.388052] [ C0] ? cs_watchdog_read (kernel/time/clocksource.c:269) # 138.74 [+0.01] [ 1008.388058] [ C0] ? clocksource_watchdog (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 kernel/time/clocksource.c:491) # 138.75 [+0.01] [ 1008.388061] [ C0] clocksource_watchdog.cold (kernel/time/clocksource.c:508) # 138.76 [+0.01] [ 1008.388066] [ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) # 138.76 [+0.01] [ 1008.388068] [ C0] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) # 138.77 [+0.01] [ 1008.388070] [ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) # 138.77 [+0.00] [ 1008.388074] [ C0] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) # 138.78 [+0.01] [ 1008.388075] [ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1)) # 138.78 [+0.01] [ 1008.388078] [ C0] ? lock_acquire (kernel/locking/lockdep.c:5833) # 138.79 [+0.00] [ 1008.388079] [ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) # 138.79 [+0.00] [ 1008.388081] [ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) # 138.80 [+0.01] [ 1008.388084] [ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) # 138.80 [+0.00] [ 1008.388085] [ C0] call_timer_fn (kernel/time/timer.c:1748) # 138.81 [+0.00] [ 1008.388086] [ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) # 138.81 [+0.00] [ 1008.388088] [ C0] ? trace_timer_expire_exit (kernel/time/timer.c:1724) # 138.82 [+0.01] [ 1008.388093] [ C0] ? _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) # 138.82 [+0.01] [ 1008.388098] [ C0] __run_timers (kernel/time/timer.c:1799 kernel/time/timer.c:2372) # 138.83 [+0.00] [ 1008.388100] [ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) # 138.83 [+0.01] [ 1008.388105] [ C0] ? call_timer_fn (kernel/time/timer.c:2343) # 138.84 [+0.00] [ 1008.388106] [ C0] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1)) # 138.84 [+0.01] [ 1008.388113] [ C0] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) # 138.85 [+0.00] [ 1008.388115] [ C0] ? run_timer_softirq (kernel/time/timer.c:2384 kernel/time/timer.c:2393 kernel/time/timer.c:2401) # 138.85 [+0.01] [ 1008.388119] [ C0] run_timer_softirq (kernel/time/timer.c:2385 kernel/time/timer.c:2393 kernel/time/timer.c:2401) # 138.86 [+0.01] [ 1008.388121] [ C0] ? __run_timers (kernel/time/timer.c:2400) # 138.86 [+0.00] [ 1008.388124] [ C0] ? trace_softirq_entry (./include/trace/events/irq.h:128 (discriminator 33)) # 138.87 [+0.01] [ 1008.388126] [ C0] handle_softirqs (kernel/softirq.c:623) # 138.87 [+0.00] [ 1008.388130] [ C0] ? trace_softirq_exit (kernel/softirq.c:580) # 138.88 [+0.01] [ 1008.388131] [ C0] ? 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) # 138.89 [+0.01] [ 1008.388135] [ C0] irq_exit_rcu (kernel/softirq.c:657 kernel/softirq.c:496 kernel/softirq.c:723 kernel/softirq.c:739) # 138.89 [+0.00] [ 1008.388137] [ C0] common_interrupt (arch/x86/kernel/irq.c:318 (discriminator 49)) # 138.90 [+0.00] [ 1008.388140] [ C0] # 138.90 [+0.00] [ 1008.388140] [ C0] # 138.90 [+0.00] [ 1008.388141] [ C0] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4473) # 138.91 [+0.01] [ 1008.388143] [ C0] asm_common_interrupt (./arch/x86/include/asm/idtentry.h:688) # 138.91 [+0.01] [ 1008.388145] [ C0] RIP: 0010:default_idle_call (kernel/sched/idle.c:131) # 138.92 [+0.01] [ 1008.388148] [ C0] Code: 3c 24 e8 3b e1 ff ff e8 c6 eb ff ff 90 e8 b0 f2 ff ff bf ff ff ff ff 89 c6 e8 d4 90 e0 fd e8 ff 6b e0 fd e8 6a 59 0c fe fb 90 90 0f 0b 90 eb af 90 65 48 8b 05 18 b5 0d 02 48 8b 00 a8 10 75 All code ======== 0: 3c 24 cmp $0x24,%al 2: e8 3b e1 ff ff call 0xffffffffffffe142 7: e8 c6 eb ff ff call 0xffffffffffffebd2 c: 90 nop d: e8 b0 f2 ff ff call 0xfffffffffffff2c2 12: bf ff ff ff ff mov $0xffffffff,%edi 17: 89 c6 mov %eax,%esi 19: e8 d4 90 e0 fd call 0xfffffffffde090f2 1e: e8 ff 6b e0 fd call 0xfffffffffde06c22 23: e8 6a 59 0c fe call 0xfffffffffe0c5992 28: fb sti 29: 90 nop 2a:* c3 ret <-- trapping instruction 2b: 90 nop 2c: 0f 0b ud2 2e: 90 nop 2f: eb af jmp 0xffffffffffffffe0 31: 90 nop 32: 65 48 8b 05 18 b5 0d mov %gs:0x20db518(%rip),%rax # 0x20db552 39: 02 3a: 48 8b 00 mov (%rax),%rax 3d: a8 10 test $0x10,%al 3f: 75 .byte 0x75 Code starting with the faulting instruction =========================================== 0: c3 ret 1: 90 nop 2: 0f 0b ud2 4: 90 nop 5: eb af jmp 0xffffffffffffffb6 7: 90 nop 8: 65 48 8b 05 18 b5 0d mov %gs:0x20db518(%rip),%rax # 0x20db528 f: 02 10: 48 8b 00 mov (%rax),%rax 13: a8 10 test $0x10,%al 15: 75 .byte 0x75 # 138.94 [+0.02] [ 1008.388149] [ C0] RSP: 0018:ffffffffb0007e10 EFLAGS: 00000282 # 138.94 [+0.01] [ 1008.388150] [ C0] RAX: 000000000280afa9 RBX: ffffffffb001e680 RCX: ffffffffaf461af6 # 138.95 [+0.01] [ 1008.388151] [ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 # 138.96 [+0.01] [ 1008.388152] [ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 # 138.97 [+0.01] [ 1008.388153] [ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 1ffffffff6000fc3 # 138.98 [+0.01] [ 1008.388153] [ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 # 138.99 [+0.01] [ 1008.388157] [ C0] ? default_idle_call (./arch/x86/include/asm/irqflags.h:42 ./arch/x86/include/asm/irqflags.h:119 kernel/sched/idle.c:129) # 138.99 [+0.01] [ 1008.388162] [ C0] cpuidle_idle_call (kernel/sched/idle.c:191) # 139.00 [+0.01] [ 1008.388166] [ C0] ? arch_cpu_idle_exit+0x40/0x40 # 139.00 [+0.01] [ 1008.388169] [ C0] ? mark_tsc_async_resets (arch/x86/kernel/tsc_sync.c:52) # 139.01 [+0.01] [ 1008.388171] [ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1)) # 139.01 [+0.01] [ 1008.388173] [ C0] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) # 139.02 [+0.01] [ 1008.388177] [ C0] do_idle (kernel/sched/idle.c:330) # 139.02 [+0.00] [ 1008.388181] [ C0] cpu_startup_entry (kernel/sched/idle.c:427) # 139.03 [+0.01] [ 1008.388183] [ C0] rest_init (init/main.c:757) # 139.03 [+0.00] [ 1008.388186] [ C0] start_kernel (init/main.c:1111) # 139.04 [+0.00] [ 1008.388191] [ C0] x86_64_start_reservations (arch/x86/kernel/head64.c:310) # 139.04 [+0.01] [ 1008.388194] [ C0] x86_64_start_kernel (??:?) # 139.05 [+0.01] [ 1008.388196] [ C0] common_startup_64 (arch/x86/kernel/head_64.S:419) | [ 960.387950][ C0] hardirqs last enabled at (42071744): asm_common_interrupt (./arch/x86/include/asm/idtentry.h:688) | [ 960.387958][ C0] hardirqs last disabled at (42071745): sysvec_apic_timer_interrupt (./arch/x86/include/asm/hardirq.h:78 arch/x86/kernel/apic/apic.c:1052) | [ 960.387962][ C0] softirqs last enabled at (41988006): handle_softirqs (kernel/softirq.c:469 (discriminator 2) kernel/softirq.c:650 (discriminator 2)) | [ 960.387966][ C0] softirqs last disabled at (41988011): irq_exit_rcu (kernel/softirq.c:657 kernel/softirq.c:496 kernel/softirq.c:723 kernel/softirq.c:739) [ 960.387973][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 960.387976][ C0] RIP: 0010:console_emit_next_record (kernel/printk/printk.c:3055) [ 960.387980][ C0] Code: ca 7f 08 84 d2 0f 85 ad 01 00 00 88 45 00 e8 52 3f 00 00 48 83 7c 24 08 00 0f 85 e8 00 00 00 9c 58 f6 c4 02 0f 85 01 01 00 00 <48> b8 00 00 00 00 00 fc ff df c7 04 03 00 00 00 00 c7 44 18 08 00 All code ======== 0: ca 7f 08 lret $0x87f 3: 84 d2 test %dl,%dl 5: 0f 85 ad 01 00 00 jne 0x1b8 b: 88 45 00 mov %al,0x0(%rbp) e: e8 52 3f 00 00 call 0x3f65 13: 48 83 7c 24 08 00 cmpq $0x0,0x8(%rsp) 19: 0f 85 e8 00 00 00 jne 0x107 1f: 9c pushf 20: 58 pop %rax 21: f6 c4 02 test $0x2,%ah 24: 0f 85 01 01 00 00 jne 0x12b 2a:* 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax <-- trapping instruction 31: fc ff df 34: c7 04 03 00 00 00 00 movl $0x0,(%rbx,%rax,1) 3b: c7 .byte 0xc7 3c: 44 18 08 sbb %r9b,(%rax) ... Code starting with the faulting instruction =========================================== 0: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax 7: fc ff df a: c7 04 03 00 00 00 00 movl $0x0,(%rbx,%rax,1) 11: c7 .byte 0xc7 12: 44 18 08 sbb %r9b,(%rax) ... [ 960.387982][ C0] RSP: 0018:ffa00000000077a8 EFLAGS: 00000246 [ 960.387984][ C0] RAX: 0000000000000096 RBX: 1ff4000000000ef9 RCX: ffffffffad2f3047 [ 960.387986][ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 [ 960.387987][ C0] RBP: ffa0000000007978 R08: 0000000000000000 R09: 0000000000000001 [ 960.387987][ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001 [ 960.387988][ C0] R13: 0000000000000205 R14: ffffffffb0820ec0 R15: ffffffffb0820ec0 [ 960.387991][ C0] FS: 0000000000000000(0000) GS:ff11000083ce3000(0000) knlGS:0000000000000000 [ 960.387994][ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 960.387996][ C0] CR2: 000056114d2f3000 CR3: 00000000118b2001 CR4: 0000000000771ef0 [ 960.387997][ C0] PKRU: 55555554 [ 960.387998][ C0] Call Trace: [ 960.388000][ C0] [ 960.388005][ C0] ? devkmsg_read (kernel/printk/printk.c:3055) [ 960.388009][ C0] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 960.388012][ C0] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 960.388014][ C0] ? console_flush_all (./include/linux/rcupdate.h:336 (discriminator 1) ./include/linux/srcu.h:335 (discriminator 1) kernel/printk/printk.c:289 (discriminator 1) kernel/printk/printk.c:3176 (discriminator 1)) [ 960.388018][ C0] console_flush_all (kernel/printk/printk.c:3200) [ 960.388022][ C0] ? console_emit_next_record (kernel/printk/printk.c:3161) [ 960.388028][ C0] console_unlock (kernel/printk/printk.c:3258 kernel/printk/printk.c:3298) [ 960.388030][ C0] ? console_flush_all (kernel/printk/printk.c:3293) [ 960.388031][ C0] ? vprintk_emit (kernel/printk/printk.c:2422 (discriminator 1)) [ 960.388035][ C0] ? __down_trylock_console_sem (kernel/printk/printk.c:333) [ 960.388037][ C0] ? vprintk_emit (kernel/printk/printk.c:2422 (discriminator 1)) [ 960.388040][ C0] vprintk_emit (kernel/printk/printk.c:2423) [ 960.388043][ C0] ? wake_up_klogd_work_func (kernel/printk/printk.c:2370) [ 960.388046][ C0] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1)) [ 960.388050][ C0] _printk (kernel/printk/printk.c:2443) [ 960.388054][ C0] ? snapshot_read.cold (kernel/printk/printk.c:2443) [ 960.388057][ C0] ? lockdep_hardirqs_on_prepare.part.0 (kernel/locking/lockdep.c:470 (discriminator 6) kernel/locking/lockdep.c:4411 (discriminator 6)) [ 960.388060][ C0] ? cs_watchdog_read (kernel/time/clocksource.c:269) [ 960.388065][ C0] ? clocksource_watchdog (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 kernel/time/clocksource.c:491) [ 960.388069][ C0] clocksource_watchdog.cold (kernel/time/clocksource.c:508) [ 960.388074][ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) [ 960.388076][ C0] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 960.388078][ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) [ 960.388081][ C0] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 960.388083][ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1)) [ 960.388086][ C0] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 960.388087][ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) [ 960.388089][ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) [ 960.388092][ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) [ 960.388093][ C0] call_timer_fn (kernel/time/timer.c:1748) [ 960.388094][ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) [ 960.388096][ C0] ? trace_timer_expire_exit (kernel/time/timer.c:1724) [ 960.388101][ C0] ? _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) [ 960.388106][ C0] __run_timers (kernel/time/timer.c:1799 kernel/time/timer.c:2372) [ 960.388108][ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) [ 960.388113][ C0] ? call_timer_fn (kernel/time/timer.c:2343) [ 960.388114][ C0] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1)) [ 960.388122][ C0] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 960.388124][ C0] ? run_timer_softirq (kernel/time/timer.c:2384 kernel/time/timer.c:2393 kernel/time/timer.c:2401) [ 960.388128][ C0] run_timer_softirq (kernel/time/timer.c:2385 kernel/time/timer.c:2393 kernel/time/timer.c:2401) [ 960.388130][ C0] ? __run_timers (kernel/time/timer.c:2400) [ 960.388133][ C0] ? trace_softirq_entry (./include/trace/events/irq.h:128 (discriminator 33)) [ 960.388134][ C0] handle_softirqs (kernel/softirq.c:623) [ 960.388139][ C0] ? trace_softirq_exit (kernel/softirq.c:580) [ 960.388140][ C0] ? 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) [ 960.388144][ C0] irq_exit_rcu (kernel/softirq.c:657 kernel/softirq.c:496 kernel/softirq.c:723 kernel/softirq.c:739) [ 960.388145][ C0] common_interrupt (arch/x86/kernel/irq.c:318 (discriminator 49)) [ 960.388148][ C0] [ 960.388149][ C0] [ 960.388149][ C0] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4473) [ 960.388152][ C0] asm_common_interrupt (./arch/x86/include/asm/idtentry.h:688) [ 960.388154][ C0] RIP: 0010:default_idle_call (kernel/sched/idle.c:131) [ 960.388157][ C0] Code: 3c 24 e8 3b e1 ff ff e8 c6 eb ff ff 90 e8 b0 f2 ff ff bf ff ff ff ff 89 c6 e8 d4 90 e0 fd e8 ff 6b e0 fd e8 6a 59 0c fe fb 90 90 0f 0b 90 eb af 90 65 48 8b 05 18 b5 0d 02 48 8b 00 a8 10 75 All code ======== 0: 3c 24 cmp $0x24,%al 2: e8 3b e1 ff ff call 0xffffffffffffe142 7: e8 c6 eb ff ff call 0xffffffffffffebd2 c: 90 nop d: e8 b0 f2 ff ff call 0xfffffffffffff2c2 12: bf ff ff ff ff mov $0xffffffff,%edi 17: 89 c6 mov %eax,%esi 19: e8 d4 90 e0 fd call 0xfffffffffde090f2 1e: e8 ff 6b e0 fd call 0xfffffffffde06c22 23: e8 6a 59 0c fe call 0xfffffffffe0c5992 28: fb sti 29: 90 nop 2a:* c3 ret <-- trapping instruction 2b: 90 nop 2c: 0f 0b ud2 2e: 90 nop 2f: eb af jmp 0xffffffffffffffe0 31: 90 nop 32: 65 48 8b 05 18 b5 0d mov %gs:0x20db518(%rip),%rax # 0x20db552 39: 02 3a: 48 8b 00 mov (%rax),%rax 3d: a8 10 test $0x10,%al 3f: 75 .byte 0x75 Code starting with the faulting instruction =========================================== 0: c3 ret 1: 90 nop 2: 0f 0b ud2 4: 90 nop 5: eb af jmp 0xffffffffffffffb6 7: 90 nop 8: 65 48 8b 05 18 b5 0d mov %gs:0x20db518(%rip),%rax # 0x20db528 f: 02 10: 48 8b 00 mov (%rax),%rax 13: a8 10 test $0x10,%al 15: 75 .byte 0x75 [ 960.388158][ C0] RSP: 0018:ffffffffb0007e10 EFLAGS: 00000282 [ 960.388159][ C0] RAX: 000000000280afa9 RBX: ffffffffb001e680 RCX: ffffffffaf461af6 [ 960.388160][ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 [ 960.388161][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 960.388161][ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 1ffffffff6000fc3 [ 960.388162][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 960.388166][ C0] ? default_idle_call (./arch/x86/include/asm/irqflags.h:42 ./arch/x86/include/asm/irqflags.h:119 kernel/sched/idle.c:129) [ 960.388171][ C0] cpuidle_idle_call (kernel/sched/idle.c:191) [ 960.388175][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 960.388177][ C0] ? mark_tsc_async_resets (arch/x86/kernel/tsc_sync.c:52) [ 960.388179][ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1)) [ 960.388181][ C0] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 960.388186][ C0] do_idle (kernel/sched/idle.c:330) [ 960.388189][ C0] cpu_startup_entry (kernel/sched/idle.c:427) [ 960.388192][ C0] rest_init (init/main.c:757) [ 960.388195][ C0] start_kernel (init/main.c:1111) [ 960.388200][ C0] x86_64_start_reservations (arch/x86/kernel/head64.c:310) [ 960.388203][ C0] x86_64_start_kernel (??:?) [ 960.388206][ C0] common_startup_64 (arch/x86/kernel/head_64.S:419) | [ 984.084401][ C0] rcu: INFO: rcu_preempt self-detected stall on CPU | [ 984.084437][ C0] rcu: 0-....: (1 GPs behind) idle=e4bc/1/0x4000000000000004 softirq=581657/581657 fqs=6299 | [ 984.084590][ C0] rcu: (t=26000 jiffies g=624709 q=1351 ncpus=4) | [ 984.084601][ C0] Tainted: [L]=SOFTLOCKUP [ 984.084602][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 984.084604][ C0] RIP: 0010:console_flush_all (./include/linux/rcupdate.h:341 (discriminator 1) ./include/linux/srcu.h:451 (discriminator 1) kernel/printk/printk.c:303 (discriminator 1) kernel/printk/printk.c:3225 (discriminator 1)) [ 984.084612][ C0] Code: 89 c6 0f 85 1b fe ff ff 41 83 ff 01 0f 87 7e 01 00 00 be 02 00 00 00 48 c7 c7 e0 16 36 b0 e8 e4 91 05 00 48 c7 c7 f0 16 36 b0 <48> 8d 35 00 00 00 00 e8 51 f6 fd ff 44 89 fe 48 c7 c7 e0 16 36 b0 All code ======== 0: 89 c6 mov %eax,%esi 2: 0f 85 1b fe ff ff jne 0xfffffffffffffe23 8: 41 83 ff 01 cmp $0x1,%r15d c: 0f 87 7e 01 00 00 ja 0x190 12: be 02 00 00 00 mov $0x2,%esi 17: 48 c7 c7 e0 16 36 b0 mov $0xffffffffb03616e0,%rdi 1e: e8 e4 91 05 00 call 0x59207 23: 48 c7 c7 f0 16 36 b0 mov $0xffffffffb03616f0,%rdi 2a:* 48 8d 35 00 00 00 00 lea 0x0(%rip),%rsi # 0x31 <-- trapping instruction 31: e8 51 f6 fd ff call 0xfffffffffffdf687 36: 44 89 fe mov %r15d,%esi 39: 48 c7 c7 e0 16 36 b0 mov $0xffffffffb03616e0,%rdi Code starting with the faulting instruction =========================================== 0: 48 8d 35 00 00 00 00 lea 0x0(%rip),%rsi # 0x7 7: e8 51 f6 fd ff call 0xfffffffffffdf65d c: 44 89 fe mov %r15d,%esi f: 48 c7 c7 e0 16 36 b0 mov $0xffffffffb03616e0,%rdi [ 984.084614][ C0] RSP: 0018:ffa0000000007880 EFLAGS: 00000286 [ 984.084618][ C0] RAX: 0000000000000003 RBX: dffffc0000000000 RCX: 0000000000000002 [ 984.084620][ C0] RDX: 0000000000000002 RSI: 1ffffffff606c2dd RDI: ffffffffb03616f0 [ 984.084620][ C0] RBP: 0000000000000001 R08: ff11000035224100 R09: fffffbfff603e844 [ 984.084621][ C0] R10: 0000000000000003 R11: 0000000000000000 R12: ffa0000000007998 [ 984.084622][ C0] R13: ffa0000000007978 R14: ffffffffb0820ec0 R15: 0000000000000001 [ 984.084625][ C0] FS: 0000000000000000(0000) GS:ff11000083ce3000(0000) knlGS:0000000000000000 [ 984.084628][ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 984.084629][ C0] CR2: 000056114d2f3000 CR3: 00000000118b2001 CR4: 0000000000771ef0 [ 984.084630][ C0] PKRU: 55555554 [ 984.084631][ C0] Call Trace: [ 984.084632][ C0] [ 984.084635][ C0] ? console_emit_next_record (kernel/printk/printk.c:3161) [ 984.084642][ C0] console_unlock (kernel/printk/printk.c:3258 kernel/printk/printk.c:3298) [ 984.084645][ C0] ? console_flush_all (kernel/printk/printk.c:3293) [ 984.084646][ C0] ? vprintk_emit (kernel/printk/printk.c:2422 (discriminator 1)) [ 984.084650][ C0] ? __down_trylock_console_sem (kernel/printk/printk.c:333) [ 984.084653][ C0] ? vprintk_emit (kernel/printk/printk.c:2422 (discriminator 1)) [ 984.084656][ C0] vprintk_emit (kernel/printk/printk.c:2423) [ 984.084659][ C0] ? wake_up_klogd_work_func (kernel/printk/printk.c:2370) [ 984.084662][ C0] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1)) [ 984.084668][ C0] _printk (kernel/printk/printk.c:2443) [ 984.084671][ C0] ? snapshot_read.cold (kernel/printk/printk.c:2443) [ 984.084674][ C0] ? lockdep_hardirqs_on_prepare.part.0 (kernel/locking/lockdep.c:470 (discriminator 6) kernel/locking/lockdep.c:4411 (discriminator 6)) [ 984.084677][ C0] ? cs_watchdog_read (kernel/time/clocksource.c:269) [ 984.084685][ C0] ? clocksource_watchdog (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 kernel/time/clocksource.c:491) [ 984.084689][ C0] clocksource_watchdog.cold (kernel/time/clocksource.c:508) [ 984.084694][ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) [ 984.084696][ C0] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 984.084698][ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) [ 984.084701][ C0] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 984.084703][ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1)) [ 984.084705][ C0] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 984.084707][ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) [ 984.084709][ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) [ 984.084712][ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) [ 984.084713][ C0] call_timer_fn (kernel/time/timer.c:1748) [ 984.084714][ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) [ 984.084716][ C0] ? trace_timer_expire_exit (kernel/time/timer.c:1724) [ 984.084721][ C0] ? _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) [ 984.084728][ C0] __run_timers (kernel/time/timer.c:1799 kernel/time/timer.c:2372) [ 984.084729][ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) [ 984.084734][ C0] ? call_timer_fn (kernel/time/timer.c:2343) [ 984.084736][ C0] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1)) [ 984.084743][ C0] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 984.084745][ C0] ? run_timer_softirq (kernel/time/timer.c:2384 kernel/time/timer.c:2393 kernel/time/timer.c:2401) [ 984.084749][ C0] run_timer_softirq (kernel/time/timer.c:2385 kernel/time/timer.c:2393 kernel/time/timer.c:2401) [ 984.084752][ C0] ? __run_timers (kernel/time/timer.c:2400) [ 984.084754][ C0] ? trace_softirq_entry (./include/trace/events/irq.h:128 (discriminator 33)) [ 984.084757][ C0] handle_softirqs (kernel/softirq.c:623) [ 984.084762][ C0] ? trace_softirq_exit (kernel/softirq.c:580) [ 984.084763][ C0] ? 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) [ 984.084767][ C0] irq_exit_rcu (kernel/softirq.c:657 kernel/softirq.c:496 kernel/softirq.c:723 kernel/softirq.c:739) [ 984.084768][ C0] common_interrupt (arch/x86/kernel/irq.c:318 (discriminator 49)) [ 984.084771][ C0] [ 984.084772][ C0] [ 984.084772][ C0] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4473) [ 984.084775][ C0] asm_common_interrupt (./arch/x86/include/asm/idtentry.h:688) [ 984.084778][ C0] RIP: 0010:default_idle_call (kernel/sched/idle.c:131) [ 984.084780][ C0] Code: 3c 24 e8 3b e1 ff ff e8 c6 eb ff ff 90 e8 b0 f2 ff ff bf ff ff ff ff 89 c6 e8 d4 90 e0 fd e8 ff 6b e0 fd e8 6a 59 0c fe fb 90 90 0f 0b 90 eb af 90 65 48 8b 05 18 b5 0d 02 48 8b 00 a8 10 75 All code ======== 0: 3c 24 cmp $0x24,%al 2: e8 3b e1 ff ff call 0xffffffffffffe142 7: e8 c6 eb ff ff call 0xffffffffffffebd2 c: 90 nop d: e8 b0 f2 ff ff call 0xfffffffffffff2c2 12: bf ff ff ff ff mov $0xffffffff,%edi 17: 89 c6 mov %eax,%esi 19: e8 d4 90 e0 fd call 0xfffffffffde090f2 1e: e8 ff 6b e0 fd call 0xfffffffffde06c22 23: e8 6a 59 0c fe call 0xfffffffffe0c5992 28: fb sti 29: 90 nop 2a:* c3 ret <-- trapping instruction 2b: 90 nop 2c: 0f 0b ud2 2e: 90 nop 2f: eb af jmp 0xffffffffffffffe0 31: 90 nop 32: 65 48 8b 05 18 b5 0d mov %gs:0x20db518(%rip),%rax # 0x20db552 39: 02 3a: 48 8b 00 mov (%rax),%rax 3d: a8 10 test $0x10,%al 3f: 75 .byte 0x75 Code starting with the faulting instruction =========================================== 0: c3 ret 1: 90 nop 2: 0f 0b ud2 4: 90 nop 5: eb af jmp 0xffffffffffffffb6 7: 90 nop 8: 65 48 8b 05 18 b5 0d mov %gs:0x20db518(%rip),%rax # 0x20db528 f: 02 10: 48 8b 00 mov (%rax),%rax 13: a8 10 test $0x10,%al 15: 75 .byte 0x75 [ 984.084781][ C0] RSP: 0018:ffffffffb0007e10 EFLAGS: 00000282 [ 984.084782][ C0] RAX: 000000000280afa9 RBX: ffffffffb001e680 RCX: ffffffffaf461af6 [ 984.084783][ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 [ 984.084784][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 984.084785][ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 1ffffffff6000fc3 [ 984.084785][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 984.084789][ C0] ? default_idle_call (./arch/x86/include/asm/irqflags.h:42 ./arch/x86/include/asm/irqflags.h:119 kernel/sched/idle.c:129) [ 984.084794][ C0] cpuidle_idle_call (kernel/sched/idle.c:191) [ 984.084797][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 984.084800][ C0] ? mark_tsc_async_resets (arch/x86/kernel/tsc_sync.c:52) [ 984.084802][ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1)) [ 984.084804][ C0] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 984.084808][ C0] do_idle (kernel/sched/idle.c:330) [ 984.084812][ C0] cpu_startup_entry (kernel/sched/idle.c:427) [ 984.084814][ C0] rest_init (init/main.c:757) [ 984.084817][ C0] start_kernel (init/main.c:1111) [ 984.084822][ C0] x86_64_start_reservations (arch/x86/kernel/head64.c:310) [ 984.084824][ C0] x86_64_start_kernel (??:?) [ 984.084827][ C0] common_startup_64 (arch/x86/kernel/head_64.S:419) | [ 1008.387951][ C0] hardirqs last disabled at (43830125): sysvec_apic_timer_interrupt (./arch/x86/include/asm/hardirq.h:78 arch/x86/kernel/apic/apic.c:1052) | [ 1008.387955][ C0] softirqs last enabled at (41988006): handle_softirqs (kernel/softirq.c:469 (discriminator 2) kernel/softirq.c:650 (discriminator 2)) | [ 1008.387959][ C0] softirqs last disabled at (41988011): irq_exit_rcu (kernel/softirq.c:657 kernel/softirq.c:496 kernel/softirq.c:723 kernel/softirq.c:739) | [ 1008.387967][ C0] Tainted: [L]=SOFTLOCKUP [ 1008.387968][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1008.387970][ C0] RIP: 0010:console_emit_next_record (kernel/printk/printk.c:3055) [ 1008.387975][ C0] Code: ca 7f 08 84 d2 0f 85 ad 01 00 00 88 45 00 e8 52 3f 00 00 48 83 7c 24 08 00 0f 85 e8 00 00 00 9c 58 f6 c4 02 0f 85 01 01 00 00 <48> b8 00 00 00 00 00 fc ff df c7 04 03 00 00 00 00 c7 44 18 08 00 All code ======== 0: ca 7f 08 lret $0x87f 3: 84 d2 test %dl,%dl 5: 0f 85 ad 01 00 00 jne 0x1b8 b: 88 45 00 mov %al,0x0(%rbp) e: e8 52 3f 00 00 call 0x3f65 13: 48 83 7c 24 08 00 cmpq $0x0,0x8(%rsp) 19: 0f 85 e8 00 00 00 jne 0x107 1f: 9c pushf 20: 58 pop %rax 21: f6 c4 02 test $0x2,%ah 24: 0f 85 01 01 00 00 jne 0x12b 2a:* 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax <-- trapping instruction 31: fc ff df 34: c7 04 03 00 00 00 00 movl $0x0,(%rbx,%rax,1) 3b: c7 .byte 0xc7 3c: 44 18 08 sbb %r9b,(%rax) ... Code starting with the faulting instruction =========================================== 0: 48 b8 00 00 00 00 00 movabs $0xdffffc0000000000,%rax 7: fc ff df a: c7 04 03 00 00 00 00 movl $0x0,(%rbx,%rax,1) 11: c7 .byte 0xc7 12: 44 18 08 sbb %r9b,(%rax) ... [ 1008.387977][ C0] RSP: 0018:ffa00000000077a8 EFLAGS: 00000246 [ 1008.387979][ C0] RAX: 0000000000000096 RBX: 1ff4000000000ef9 RCX: ffffffffad2f3047 [ 1008.387981][ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 [ 1008.387981][ C0] RBP: ffa0000000007978 R08: 0000000000000000 R09: 0000000000000001 [ 1008.387982][ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000001 [ 1008.387983][ C0] R13: 000000000000023c R14: ffffffffb0820ec0 R15: ffffffffb0820ec0 [ 1008.387986][ C0] FS: 0000000000000000(0000) GS:ff11000083ce3000(0000) knlGS:0000000000000000 [ 1008.387989][ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 1008.387990][ C0] CR2: 000056114d2f3000 CR3: 00000000118b2001 CR4: 0000000000771ef0 [ 1008.387991][ C0] PKRU: 55555554 [ 1008.387992][ C0] Call Trace: [ 1008.387993][ C0] [ 1008.387997][ C0] ? devkmsg_read (kernel/printk/printk.c:3055) [ 1008.388001][ C0] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 1008.388005][ C0] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 1008.388007][ C0] ? console_flush_all (./include/linux/rcupdate.h:336 (discriminator 1) ./include/linux/srcu.h:335 (discriminator 1) kernel/printk/printk.c:289 (discriminator 1) kernel/printk/printk.c:3176 (discriminator 1)) [ 1008.388010][ C0] console_flush_all (kernel/printk/printk.c:3200) [ 1008.388015][ C0] ? console_emit_next_record (kernel/printk/printk.c:3161) [ 1008.388020][ C0] console_unlock (kernel/printk/printk.c:3258 kernel/printk/printk.c:3298) [ 1008.388023][ C0] ? console_flush_all (kernel/printk/printk.c:3293) [ 1008.388024][ C0] ? vprintk_emit (kernel/printk/printk.c:2422 (discriminator 1)) [ 1008.388027][ C0] ? __down_trylock_console_sem (kernel/printk/printk.c:333) [ 1008.388030][ C0] ? vprintk_emit (kernel/printk/printk.c:2422 (discriminator 1)) [ 1008.388032][ C0] vprintk_emit (kernel/printk/printk.c:2423) [ 1008.388036][ C0] ? wake_up_klogd_work_func (kernel/printk/printk.c:2370) [ 1008.388039][ C0] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1)) [ 1008.388043][ C0] _printk (kernel/printk/printk.c:2443) [ 1008.388046][ C0] ? snapshot_read.cold (kernel/printk/printk.c:2443) [ 1008.388049][ C0] ? lockdep_hardirqs_on_prepare.part.0 (kernel/locking/lockdep.c:470 (discriminator 6) kernel/locking/lockdep.c:4411 (discriminator 6)) [ 1008.388052][ C0] ? cs_watchdog_read (kernel/time/clocksource.c:269) [ 1008.388058][ C0] ? clocksource_watchdog (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 kernel/time/clocksource.c:491) [ 1008.388061][ C0] clocksource_watchdog.cold (kernel/time/clocksource.c:508) [ 1008.388066][ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) [ 1008.388068][ C0] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 1008.388070][ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) [ 1008.388074][ C0] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 1008.388075][ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1)) [ 1008.388078][ C0] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 1008.388079][ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) [ 1008.388081][ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) [ 1008.388084][ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) [ 1008.388085][ C0] call_timer_fn (kernel/time/timer.c:1748) [ 1008.388086][ C0] ? call_timer_fn (./include/linux/lockdep.h:31 kernel/time/timer.c:1737) [ 1008.388088][ C0] ? trace_timer_expire_exit (kernel/time/timer.c:1724) [ 1008.388093][ C0] ? _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) [ 1008.388098][ C0] __run_timers (kernel/time/timer.c:1799 kernel/time/timer.c:2372) [ 1008.388100][ C0] ? __clocksource_unstable (kernel/time/clocksource.c:425) [ 1008.388105][ C0] ? call_timer_fn (kernel/time/timer.c:2343) [ 1008.388106][ C0] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1)) [ 1008.388113][ C0] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 1008.388115][ C0] ? run_timer_softirq (kernel/time/timer.c:2384 kernel/time/timer.c:2393 kernel/time/timer.c:2401) [ 1008.388119][ C0] run_timer_softirq (kernel/time/timer.c:2385 kernel/time/timer.c:2393 kernel/time/timer.c:2401) [ 1008.388121][ C0] ? __run_timers (kernel/time/timer.c:2400) [ 1008.388124][ C0] ? trace_softirq_entry (./include/trace/events/irq.h:128 (discriminator 33)) [ 1008.388126][ C0] handle_softirqs (kernel/softirq.c:623) [ 1008.388130][ C0] ? trace_softirq_exit (kernel/softirq.c:580) [ 1008.388131][ C0] ? 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) [ 1008.388135][ C0] irq_exit_rcu (kernel/softirq.c:657 kernel/softirq.c:496 kernel/softirq.c:723 kernel/softirq.c:739) [ 1008.388137][ C0] common_interrupt (arch/x86/kernel/irq.c:318 (discriminator 49)) [ 1008.388140][ C0] [ 1008.388140][ C0] [ 1008.388141][ C0] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4473) [ 1008.388143][ C0] asm_common_interrupt (./arch/x86/include/asm/idtentry.h:688) [ 1008.388145][ C0] RIP: 0010:default_idle_call (kernel/sched/idle.c:131) [ 1008.388148][ C0] Code: 3c 24 e8 3b e1 ff ff e8 c6 eb ff ff 90 e8 b0 f2 ff ff bf ff ff ff ff 89 c6 e8 d4 90 e0 fd e8 ff 6b e0 fd e8 6a 59 0c fe fb 90 90 0f 0b 90 eb af 90 65 48 8b 05 18 b5 0d 02 48 8b 00 a8 10 75 All code ======== 0: 3c 24 cmp $0x24,%al 2: e8 3b e1 ff ff call 0xffffffffffffe142 7: e8 c6 eb ff ff call 0xffffffffffffebd2 c: 90 nop d: e8 b0 f2 ff ff call 0xfffffffffffff2c2 12: bf ff ff ff ff mov $0xffffffff,%edi 17: 89 c6 mov %eax,%esi 19: e8 d4 90 e0 fd call 0xfffffffffde090f2 1e: e8 ff 6b e0 fd call 0xfffffffffde06c22 23: e8 6a 59 0c fe call 0xfffffffffe0c5992 28: fb sti 29: 90 nop 2a:* c3 ret <-- trapping instruction 2b: 90 nop 2c: 0f 0b ud2 2e: 90 nop 2f: eb af jmp 0xffffffffffffffe0 31: 90 nop 32: 65 48 8b 05 18 b5 0d mov %gs:0x20db518(%rip),%rax # 0x20db552 39: 02 3a: 48 8b 00 mov (%rax),%rax 3d: a8 10 test $0x10,%al 3f: 75 .byte 0x75 Code starting with the faulting instruction =========================================== 0: c3 ret 1: 90 nop 2: 0f 0b ud2 4: 90 nop 5: eb af jmp 0xffffffffffffffb6 7: 90 nop 8: 65 48 8b 05 18 b5 0d mov %gs:0x20db518(%rip),%rax # 0x20db528 f: 02 10: 48 8b 00 mov (%rax),%rax 13: a8 10 test $0x10,%al 15: 75 .byte 0x75 [ 1008.388149][ C0] RSP: 0018:ffffffffb0007e10 EFLAGS: 00000282 [ 1008.388150][ C0] RAX: 000000000280afa9 RBX: ffffffffb001e680 RCX: ffffffffaf461af6 [ 1008.388151][ C0] RDX: ffffffffb001e680 RSI: ffffffffafd99176 RDI: ffffffffaf85d0c0 [ 1008.388152][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000001 [ 1008.388153][ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 1ffffffff6000fc3 [ 1008.388153][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 1008.388157][ C0] ? default_idle_call (./arch/x86/include/asm/irqflags.h:42 ./arch/x86/include/asm/irqflags.h:119 kernel/sched/idle.c:129) [ 1008.388162][ C0] cpuidle_idle_call (kernel/sched/idle.c:191) [ 1008.388166][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 1008.388169][ C0] ? mark_tsc_async_resets (arch/x86/kernel/tsc_sync.c:52) [ 1008.388171][ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1)) [ 1008.388173][ C0] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 1008.388177][ C0] do_idle (kernel/sched/idle.c:330) [ 1008.388181][ C0] cpu_startup_entry (kernel/sched/idle.c:427) [ 1008.388183][ C0] rest_init (init/main.c:757) [ 1008.388186][ C0] start_kernel (init/main.c:1111) [ 1008.388191][ C0] x86_64_start_reservations (arch/x86/kernel/head64.c:310) [ 1008.388194][ C0] x86_64_start_kernel (??:?) Finger prints: asm_common_interrupt:sysvec_apic_timer_interrupt:handle_softirqs:irq_exit_rcu:console_emit_next_record sysvec_apic_timer_interrupt:handle_softirqs:irq_exit_rcu:console_emit_next_record:console_flush_all console_flush_all:console_unlock:vprintk_emit:_printk:call_timer_fn